{"level":"warn","ts":"2026-04-07T00:06:33.885227Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:06:33.885308Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:06:33.885364Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:06:33.885373Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-07T00:06:33.885388Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:06:33.886029Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-04-07T00:06:33.886101Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:06:33.890364Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.019424ms"} {"level":"info","ts":"2026-04-07T00:06:33.895939Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-04-07T00:06:33.896029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:06:33.896061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:06:33.896083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:06:33.896098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:06:33.896145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-04-07T00:06:33.902917Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:06:33.905052Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:06:33.906318Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T00:06:33.908932Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:06:33.909205Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:06:33.909278Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:06:33.90948Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:06:33.909581Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:06:33.910928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-04-07T00:06:33.911516Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-07T00:06:33.912839Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:06:33.912939Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-07T00:06:33.912975Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-07T00:06:33.913206Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:06:33.913264Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:06:34.697337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:06:34.697384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:06:34.697399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-04-07T00:06:34.69741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:06:34.69743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-07T00:06:34.69744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:06:34.697447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-07T00:06:34.698482Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:06:34.698531Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:34.69871Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:06:34.698748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:06:34.698914Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:06:34.699055Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:06:34.700327Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:34.700495Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:34.700543Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:06:34.702537Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-04-07T00:06:34.702898Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:06:52.297634Z","caller":"traceutil/trace.go:171","msg":"trace[2137951673] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"187.27179ms","start":"2026-04-07T00:06:52.110336Z","end":"2026-04-07T00:06:52.297608Z","steps":["trace[2137951673] 'process raft request' (duration: 187.106166ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:06:57.720458Z","caller":"traceutil/trace.go:171","msg":"trace[834442182] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"119.644169ms","start":"2026-04-07T00:06:57.600774Z","end":"2026-04-07T00:06:57.720418Z","steps":["trace[834442182] 'read index received' (duration: 119.449264ms)","trace[834442182] 'applied index is now lower than readState.Index' (duration: 193.345µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:06:57.720595Z","caller":"traceutil/trace.go:171","msg":"trace[1247435337] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"220.256314ms","start":"2026-04-07T00:06:57.500286Z","end":"2026-04-07T00:06:57.720543Z","steps":["trace[1247435337] 'process raft request' (duration: 219.930446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:06:57.720645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.841324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-07T00:06:57.720768Z","caller":"traceutil/trace.go:171","msg":"trace[1766618632] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:416; }","duration":"120.030279ms","start":"2026-04-07T00:06:57.600716Z","end":"2026-04-07T00:06:57.720746Z","steps":["trace[1766618632] 'agreement among raft nodes before linearized reading' (duration: 119.847054ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:02.888773Z","caller":"traceutil/trace.go:171","msg":"trace[1093352743] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"149.398766ms","start":"2026-04-07T00:07:02.739349Z","end":"2026-04-07T00:07:02.888747Z","steps":["trace[1093352743] 'process raft request' (duration: 122.311281ms)","trace[1093352743] 'compare' (duration: 26.975622ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:02.889244Z","caller":"traceutil/trace.go:171","msg":"trace[1330518005] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"139.201407ms","start":"2026-04-07T00:07:02.749972Z","end":"2026-04-07T00:07:02.889173Z","steps":["trace[1330518005] 'process raft request' (duration: 139.030042ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:02.889344Z","caller":"traceutil/trace.go:171","msg":"trace[1972659983] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"149.724395ms","start":"2026-04-07T00:07:02.739539Z","end":"2026-04-07T00:07:02.889263Z","steps":["trace[1972659983] 'process raft request' (duration: 149.355115ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:02.889382Z","caller":"traceutil/trace.go:171","msg":"trace[67770394] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"125.217058ms","start":"2026-04-07T00:07:02.76412Z","end":"2026-04-07T00:07:02.889337Z","steps":["trace[67770394] 'process raft request' (duration: 124.93207ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:02.88972Z","caller":"traceutil/trace.go:171","msg":"trace[1012958413] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:472; }","duration":"135.764516ms","start":"2026-04-07T00:07:02.753933Z","end":"2026-04-07T00:07:02.889697Z","steps":["trace[1012958413] 'read index received' (duration: 107.910181ms)","trace[1012958413] 'applied index is now lower than readState.Index' (duration: 27.853515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:07:02.889917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.967281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-04-07T00:07:02.889945Z","caller":"traceutil/trace.go:171","msg":"trace[245779629] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:459; }","duration":"136.047804ms","start":"2026-04-07T00:07:02.753889Z","end":"2026-04-07T00:07:02.889937Z","steps":["trace[245779629] 'agreement among raft nodes before linearized reading' (duration: 135.89728ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:02.890037Z","caller":"traceutil/trace.go:171","msg":"trace[269511751] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"125.366751ms","start":"2026-04-07T00:07:02.764618Z","end":"2026-04-07T00:07:02.889985Z","steps":["trace[269511751] 'process raft request' (duration: 124.686813ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:06.091018Z","caller":"traceutil/trace.go:171","msg":"trace[717491972] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"104.439908ms","start":"2026-04-07T00:07:05.986493Z","end":"2026-04-07T00:07:06.090933Z","steps":["trace[717491972] 'process raft request' (duration: 93.063588ms)","trace[717491972] 'compare' (duration: 11.057322ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:12.571162Z","caller":"traceutil/trace.go:171","msg":"trace[323272131] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"172.721083ms","start":"2026-04-07T00:07:12.398413Z","end":"2026-04-07T00:07:12.571134Z","steps":["trace[323272131] 'read index received' (duration: 172.417845ms)","trace[323272131] 'applied index is now lower than readState.Index' (duration: 300.208µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:07:12.57134Z","caller":"traceutil/trace.go:171","msg":"trace[378300578] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"185.378447ms","start":"2026-04-07T00:07:12.385931Z","end":"2026-04-07T00:07:12.57131Z","steps":["trace[378300578] 'process raft request' (duration: 184.984267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:12.571579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.166014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T00:07:12.57158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.225969ms","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-04-07T00:07:12.571682Z","caller":"traceutil/trace.go:171","msg":"trace[832721724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"150.364682ms","start":"2026-04-07T00:07:12.421304Z","end":"2026-04-07T00:07:12.571669Z","steps":["trace[832721724] 'agreement among raft nodes before linearized reading' (duration: 150.157186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:12.5717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.885562ms","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-04-07T00:07:12.571782Z","caller":"traceutil/trace.go:171","msg":"trace[2114915966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"173.264837ms","start":"2026-04-07T00:07:12.398362Z","end":"2026-04-07T00:07:12.571627Z","steps":["trace[2114915966] 'agreement among raft nodes before linearized reading' (duration: 172.920518ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:12.571896Z","caller":"traceutil/trace.go:171","msg":"trace[1483830006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"169.082757ms","start":"2026-04-07T00:07:12.402764Z","end":"2026-04-07T00:07:12.571847Z","steps":["trace[1483830006] 'agreement among raft nodes before linearized reading' (duration: 168.591294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:07:13.335236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.917029ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-07T00:07:28.696862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.503723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8x2vw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-07T00:07:28.69697Z","caller":"traceutil/trace.go:171","msg":"trace[1331037694] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-8x2vw; range_end:; response_count:1; response_revision:751; }","duration":"212.656957ms","start":"2026-04-07T00:07:28.484294Z","end":"2026-04-07T00:07:28.696951Z","steps":["trace[1331037694] 'range keys from in-memory index tree' (duration: 212.288868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:27.534576Z","caller":"traceutil/trace.go:171","msg":"trace[935489917] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"143.874231ms","start":"2026-04-07T00:09:27.390668Z","end":"2026-04-07T00:09:27.534542Z","steps":["trace[935489917] 'process raft request' (duration: 143.696436ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:27.583467Z","caller":"traceutil/trace.go:171","msg":"trace[1327038527] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2182; }","duration":"181.035323ms","start":"2026-04-07T00:09:27.402406Z","end":"2026-04-07T00:09:27.583441Z","steps":["trace[1327038527] 'read index received' (duration: 132.043412ms)","trace[1327038527] 'applied index is now lower than readState.Index' (duration: 48.991021ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:27.583639Z","caller":"traceutil/trace.go:171","msg":"trace[455694380] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"191.154366ms","start":"2026-04-07T00:09:27.392438Z","end":"2026-04-07T00:09:27.583592Z","steps":["trace[455694380] 'process raft request' (duration: 190.869109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:27.583743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.29253ms","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-04-07T00:09:27.583783Z","caller":"traceutil/trace.go:171","msg":"trace[179064597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2101; }","duration":"181.411273ms","start":"2026-04-07T00:09:27.402359Z","end":"2026-04-07T00:09:27.58377Z","steps":["trace[179064597] 'agreement among raft nodes before linearized reading' (duration: 181.217268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:27.58384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.969022ms","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-04-07T00:09:27.5839Z","caller":"traceutil/trace.go:171","msg":"trace[1004481222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2101; }","duration":"181.045573ms","start":"2026-04-07T00:09:27.402837Z","end":"2026-04-07T00:09:27.583882Z","steps":["trace[1004481222] 'agreement among raft nodes before linearized reading' (duration: 180.90848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:27.87067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.342222ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-07T00:09:27.871077Z","caller":"traceutil/trace.go:171","msg":"trace[1396034995] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"167.814246ms","start":"2026-04-07T00:09:27.703237Z","end":"2026-04-07T00:09:27.871052Z","steps":["trace[1396034995] 'process raft request' (duration: 167.723614ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:27.87131Z","caller":"traceutil/trace.go:171","msg":"trace[1522606421] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"258.590125ms","start":"2026-04-07T00:09:27.612678Z","end":"2026-04-07T00:09:27.871268Z","steps":["trace[1522606421] 'process raft request' (duration: 258.139733ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:27.998963Z","caller":"traceutil/trace.go:171","msg":"trace[221597694] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"126.355839ms","start":"2026-04-07T00:09:27.872571Z","end":"2026-04-07T00:09:27.998926Z","steps":["trace[221597694] 'process raft request' (duration: 125.956078ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:27.998958Z","caller":"traceutil/trace.go:171","msg":"trace[2142476836] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"110.87069ms","start":"2026-04-07T00:09:27.888046Z","end":"2026-04-07T00:09:27.998916Z","steps":["trace[2142476836] 'read index received' (duration: 110.334896ms)","trace[2142476836] 'applied index is now lower than readState.Index' (duration: 532.874µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:09:27.9994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.318203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T00:09:27.999498Z","caller":"traceutil/trace.go:171","msg":"trace[863952364] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"111.473217ms","start":"2026-04-07T00:09:27.888Z","end":"2026-04-07T00:09:27.999473Z","steps":["trace[863952364] 'agreement among raft nodes before linearized reading' (duration: 111.033705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:33.23779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.912503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T00:09:33.237914Z","caller":"traceutil/trace.go:171","msg":"trace[238354838] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2130; }","duration":"114.094278ms","start":"2026-04-07T00:09:33.123787Z","end":"2026-04-07T00:09:33.237882Z","steps":["trace[238354838] 'range keys from in-memory index tree' (duration: 113.679407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:33.238161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.884175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-07T00:09:33.238212Z","caller":"traceutil/trace.go:171","msg":"trace[223671057] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2130; }","duration":"145.983407ms","start":"2026-04-07T00:09:33.092216Z","end":"2026-04-07T00:09:33.2382Z","steps":["trace[223671057] 'range keys from in-memory index tree' (duration: 145.69649ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:02.476672Z","caller":"traceutil/trace.go:171","msg":"trace[1729278434] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"105.450557ms","start":"2026-04-07T00:11:02.371168Z","end":"2026-04-07T00:11:02.476618Z","steps":["trace[1729278434] 'process raft request' (duration: 70.747761ms)","trace[1729278434] 'compare' (duration: 34.534572ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:22.397517Z","caller":"traceutil/trace.go:171","msg":"trace[1144310993] linearizableReadLoop","detail":"{readStateIndex:3035; appliedIndex:3034; }","duration":"144.055946ms","start":"2026-04-07T00:11:22.25343Z","end":"2026-04-07T00:11:22.397486Z","steps":["trace[1144310993] 'read index received' (duration: 54.126478ms)","trace[1144310993] 'applied index is now lower than readState.Index' (duration: 89.928338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:22.397758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.302523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-07T00:11:22.397838Z","caller":"traceutil/trace.go:171","msg":"trace[908199529] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2920; }","duration":"144.435086ms","start":"2026-04-07T00:11:22.253391Z","end":"2026-04-07T00:11:22.397826Z","steps":["trace[908199529] 'agreement among raft nodes before linearized reading' (duration: 144.21206ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:22.397954Z","caller":"traceutil/trace.go:171","msg":"trace[1555531415] transaction","detail":"{read_only:false; response_revision:2920; number_of_response:1; }","duration":"155.318471ms","start":"2026-04-07T00:11:22.242594Z","end":"2026-04-07T00:11:22.397912Z","steps":["trace[1555531415] 'process raft request' (duration: 65.033573ms)","trace[1555531415] 'compare' (duration: 89.63131ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:09.292676Z","caller":"traceutil/trace.go:171","msg":"trace[719358346] transaction","detail":"{read_only:false; response_revision:3654; number_of_response:1; }","duration":"104.285845ms","start":"2026-04-07T00:13:09.188357Z","end":"2026-04-07T00:13:09.292643Z","steps":["trace[719358346] 'process raft request' (duration: 17.118846ms)","trace[719358346] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/daemonsets/monitoring/node-feature-discovery-worker; req_size:5688; } (duration: 86.858871ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:09.292908Z","caller":"traceutil/trace.go:171","msg":"trace[1743220497] transaction","detail":"{read_only:false; response_revision:3655; number_of_response:1; }","duration":"104.378928ms","start":"2026-04-07T00:13:09.188484Z","end":"2026-04-07T00:13:09.292863Z","steps":["trace[1743220497] 'process raft request' (duration: 104.07413ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:04.268836Z","caller":"traceutil/trace.go:171","msg":"trace[1908336253] linearizableReadLoop","detail":"{readStateIndex:5059; appliedIndex:5058; }","duration":"187.834072ms","start":"2026-04-07T00:15:04.08098Z","end":"2026-04-07T00:15:04.268814Z","steps":["trace[1908336253] 'read index received' (duration: 149.027722ms)","trace[1908336253] 'applied index is now lower than readState.Index' (duration: 38.80561ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:04.26888Z","caller":"traceutil/trace.go:171","msg":"trace[1364278646] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"188.072729ms","start":"2026-04-07T00:15:04.080765Z","end":"2026-04-07T00:15:04.268838Z","steps":["trace[1364278646] 'process raft request' (duration: 149.223687ms)","trace[1364278646] 'compare' (duration: 38.736539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:04.269056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.062138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T00:15:04.26909Z","caller":"traceutil/trace.go:171","msg":"trace[86344890] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4871; }","duration":"188.14574ms","start":"2026-04-07T00:15:04.080937Z","end":"2026-04-07T00:15:04.269082Z","steps":["trace[86344890] 'agreement among raft nodes before linearized reading' (duration: 187.976836ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:04.472698Z","caller":"traceutil/trace.go:171","msg":"trace[467383055] linearizableReadLoop","detail":"{readStateIndex:5060; appliedIndex:5059; }","duration":"203.781667ms","start":"2026-04-07T00:15:04.268891Z","end":"2026-04-07T00:15:04.472672Z","steps":["trace[467383055] 'read index received' (duration: 105.247346ms)","trace[467383055] 'applied index is now lower than readState.Index' (duration: 98.533561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:04.472852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.760949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:15:04.472899Z","caller":"traceutil/trace.go:171","msg":"trace[43082547] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:4872; }","duration":"385.860902ms","start":"2026-04-07T00:15:04.087026Z","end":"2026-04-07T00:15:04.472887Z","steps":["trace[43082547] 'agreement among raft nodes before linearized reading' (duration: 385.733269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:04.472928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.928417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-07T00:15:04.472963Z","caller":"traceutil/trace.go:171","msg":"trace[1526505787] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4872; }","duration":"391.990179ms","start":"2026-04-07T00:15:04.080961Z","end":"2026-04-07T00:15:04.472952Z","steps":["trace[1526505787] 'agreement among raft nodes before linearized reading' (duration: 391.843925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:04.47293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:15:04.087006Z","time spent":"385.915783ms","remote":"127.0.0.1:37510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:15:04.472988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:15:04.08094Z","time spent":"392.038401ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-04-07T00:15:04.472807Z","caller":"traceutil/trace.go:171","msg":"trace[2025669403] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"379.309173ms","start":"2026-04-07T00:15:04.093453Z","end":"2026-04-07T00:15:04.472762Z","steps":["trace[2025669403] 'process raft request' (duration: 280.758101ms)","trace[2025669403] 'compare' (duration: 98.338046ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:04.473024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.786218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-07T00:15:04.473319Z","caller":"traceutil/trace.go:171","msg":"trace[1010336315] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4872; }","duration":"200.329223ms","start":"2026-04-07T00:15:04.272973Z","end":"2026-04-07T00:15:04.473303Z","steps":["trace[1010336315] 'agreement among raft nodes before linearized reading' (duration: 199.911421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:04.474235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:15:04.093418Z","time spent":"379.879348ms","remote":"127.0.0.1:46314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2449,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:15:04.755083Z","caller":"traceutil/trace.go:171","msg":"trace[1933653584] linearizableReadLoop","detail":"{readStateIndex:5061; appliedIndex:5060; }","duration":"268.68679ms","start":"2026-04-07T00:15:04.486374Z","end":"2026-04-07T00:15:04.75506Z","steps":["trace[1933653584] 'read index received' (duration: 268.444344ms)","trace[1933653584] 'applied index is now lower than readState.Index' (duration: 241.396µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:04.755233Z","caller":"traceutil/trace.go:171","msg":"trace[1622798792] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"269.082541ms","start":"2026-04-07T00:15:04.486142Z","end":"2026-04-07T00:15:04.755225Z","steps":["trace[1622798792] 'process raft request' (duration: 268.754612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:04.755414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.873588ms","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-04-07T00:15:04.755443Z","caller":"traceutil/trace.go:171","msg":"trace[993894717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4873; }","duration":"226.94668ms","start":"2026-04-07T00:15:04.528489Z","end":"2026-04-07T00:15:04.755435Z","steps":["trace[993894717] 'agreement among raft nodes before linearized reading' (duration: 226.796975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:04.755567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.174894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-07T00:15:04.755697Z","caller":"traceutil/trace.go:171","msg":"trace[769898878] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4873; }","duration":"269.303327ms","start":"2026-04-07T00:15:04.486329Z","end":"2026-04-07T00:15:04.755633Z","steps":["trace[769898878] 'agreement among raft nodes before linearized reading' (duration: 269.04411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:10.136661Z","caller":"traceutil/trace.go:171","msg":"trace[1016813557] linearizableReadLoop","detail":"{readStateIndex:5270; appliedIndex:5269; }","duration":"120.41357ms","start":"2026-04-07T00:15:10.016205Z","end":"2026-04-07T00:15:10.136618Z","steps":["trace[1016813557] 'read index received' (duration: 120.256626ms)","trace[1016813557] 'applied index is now lower than readState.Index' (duration: 155.814µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:10.136788Z","caller":"traceutil/trace.go:171","msg":"trace[1326648447] transaction","detail":"{read_only:false; response_revision:5077; number_of_response:1; }","duration":"136.760187ms","start":"2026-04-07T00:15:09.999985Z","end":"2026-04-07T00:15:10.136746Z","steps":["trace[1326648447] 'process raft request' (duration: 136.456428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:10.137023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.79889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-04-07T00:15:10.137083Z","caller":"traceutil/trace.go:171","msg":"trace[1090895506] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:5077; }","duration":"120.916823ms","start":"2026-04-07T00:15:10.016153Z","end":"2026-04-07T00:15:10.13707Z","steps":["trace[1090895506] 'agreement among raft nodes before linearized reading' (duration: 120.637616ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:20.121374Z","caller":"traceutil/trace.go:171","msg":"trace[621301326] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"101.435802ms","start":"2026-04-07T00:15:20.019893Z","end":"2026-04-07T00:15:20.121329Z","steps":["trace[621301326] 'process raft request' (duration: 96.62572ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:20.121887Z","caller":"traceutil/trace.go:171","msg":"trace[2005837818] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"101.877523ms","start":"2026-04-07T00:15:20.019987Z","end":"2026-04-07T00:15:20.121864Z","steps":["trace[2005837818] 'process raft request' (duration: 101.275057ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:20.121905Z","caller":"traceutil/trace.go:171","msg":"trace[1573561753] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"101.754211ms","start":"2026-04-07T00:15:20.02012Z","end":"2026-04-07T00:15:20.121875Z","steps":["trace[1573561753] 'process raft request' (duration: 101.459203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:27.538821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.144236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:15:27.539068Z","caller":"traceutil/trace.go:171","msg":"trace[172566172] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"222.273723ms","start":"2026-04-07T00:15:27.316732Z","end":"2026-04-07T00:15:27.539006Z","steps":["trace[172566172] 'process raft request' (duration: 39.87617ms)","trace[172566172] 'compare' (duration: 182.035393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:27.80804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.137896ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-07T00:15:27.955885Z","caller":"traceutil/trace.go:171","msg":"trace[39493156] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"145.33587ms","start":"2026-04-07T00:15:27.81048Z","end":"2026-04-07T00:15:27.955815Z","steps":["trace[39493156] 'process raft request' (duration: 114.802536ms)","trace[39493156] 'compare' (duration: 30.419051ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:28.016795Z","caller":"traceutil/trace.go:171","msg":"trace[1007863899] linearizableReadLoop","detail":"{readStateIndex:5596; appliedIndex:5593; }","duration":"203.425458ms","start":"2026-04-07T00:15:27.813344Z","end":"2026-04-07T00:15:28.01677Z","steps":["trace[1007863899] 'read index received' (duration: 111.956129ms)","trace[1007863899] 'applied index is now lower than readState.Index' (duration: 91.468199ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:28.01685Z","caller":"traceutil/trace.go:171","msg":"trace[755176906] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"203.678695ms","start":"2026-04-07T00:15:27.813154Z","end":"2026-04-07T00:15:28.016832Z","steps":["trace[755176906] 'process raft request' (duration: 203.491349ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:28.016818Z","caller":"traceutil/trace.go:171","msg":"trace[1635713306] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"203.705015ms","start":"2026-04-07T00:15:27.813076Z","end":"2026-04-07T00:15:28.016781Z","steps":["trace[1635713306] 'process raft request' (duration: 201.965318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:28.017081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.106096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T00:15:28.017232Z","caller":"traceutil/trace.go:171","msg":"trace[1901463097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5395; }","duration":"167.2823ms","start":"2026-04-07T00:15:27.849907Z","end":"2026-04-07T00:15:28.017189Z","steps":["trace[1901463097] 'agreement among raft nodes before linearized reading' (duration: 167.052344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:28.017227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.856659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80096"} {"level":"info","ts":"2026-04-07T00:15:28.017318Z","caller":"traceutil/trace.go:171","msg":"trace[1593536908] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5395; }","duration":"203.975013ms","start":"2026-04-07T00:15:27.813318Z","end":"2026-04-07T00:15:28.017293Z","steps":["trace[1593536908] 'agreement among raft nodes before linearized reading' (duration: 203.601343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.232877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.085115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-04-07T00:16:28.23299Z","caller":"traceutil/trace.go:171","msg":"trace[1441184180] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5980; }","duration":"105.23683ms","start":"2026-04-07T00:16:28.127731Z","end":"2026-04-07T00:16:28.232968Z","steps":["trace[1441184180] 'range keys from in-memory index tree' (duration: 104.89059ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.471646Z","caller":"traceutil/trace.go:171","msg":"trace[1852065866] linearizableReadLoop","detail":"{readStateIndex:6200; appliedIndex:6198; }","duration":"135.946896ms","start":"2026-04-07T00:16:28.335668Z","end":"2026-04-07T00:16:28.471614Z","steps":["trace[1852065866] 'read index received' (duration: 23.649044ms)","trace[1852065866] 'applied index is now lower than readState.Index' (duration: 112.296562ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:28.471703Z","caller":"traceutil/trace.go:171","msg":"trace[2137761739] transaction","detail":"{read_only:false; response_revision:5983; number_of_response:1; }","duration":"136.625125ms","start":"2026-04-07T00:16:28.33504Z","end":"2026-04-07T00:16:28.471665Z","steps":["trace[2137761739] 'process raft request' (duration: 136.383508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:28.471876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.188904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5ntcl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T00:16:28.471916Z","caller":"traceutil/trace.go:171","msg":"trace[1114990561] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5ntcl; range_end:; response_count:1; response_revision:5984; }","duration":"136.282735ms","start":"2026-04-07T00:16:28.335625Z","end":"2026-04-07T00:16:28.471907Z","steps":["trace[1114990561] 'agreement among raft nodes before linearized reading' (duration: 136.108051ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:28.47192Z","caller":"traceutil/trace.go:171","msg":"trace[1097758061] transaction","detail":"{read_only:false; response_revision:5984; number_of_response:1; }","duration":"135.7073ms","start":"2026-04-07T00:16:28.336182Z","end":"2026-04-07T00:16:28.471889Z","steps":["trace[1097758061] 'process raft request' (duration: 135.396971ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:34.729157Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-04-07T00:16:34.802782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"71.470059ms","hash":3422297199,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":33607680,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:16:34.802862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3422297199,"revision":2988,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:21:28.719786Z","caller":"traceutil/trace.go:171","msg":"trace[1399326924] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"187.008064ms","start":"2026-04-07T00:21:28.532761Z","end":"2026-04-07T00:21:28.719769Z","steps":["trace[1399326924] 'process raft request' (duration: 186.891261ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:21:34.73773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6039} {"level":"info","ts":"2026-04-07T00:21:34.839774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6039,"took":"98.588766ms","hash":4209915275,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":23785472,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T00:21:34.839868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4209915275,"revision":6039,"compact-revision":2988} {"level":"info","ts":"2026-04-07T00:21:40.298611Z","caller":"traceutil/trace.go:171","msg":"trace[281819191] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"166.609199ms","start":"2026-04-07T00:21:40.131957Z","end":"2026-04-07T00:21:40.298566Z","steps":["trace[281819191] 'process raft request' (duration: 116.050142ms)","trace[281819191] 'compare' (duration: 50.252699ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:21:40.298835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.22793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:21:40.298974Z","caller":"traceutil/trace.go:171","msg":"trace[305865960] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8294; }","duration":"100.464887ms","start":"2026-04-07T00:21:40.198491Z","end":"2026-04-07T00:21:40.298956Z","steps":["trace[305865960] 'agreement among raft nodes before linearized reading' (duration: 100.081776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:40.578962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.804043ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:21:40.579131Z","caller":"traceutil/trace.go:171","msg":"trace[136726738] linearizableReadLoop","detail":"{readStateIndex:8591; appliedIndex:8590; }","duration":"221.146675ms","start":"2026-04-07T00:21:40.357964Z","end":"2026-04-07T00:21:40.579111Z","steps":["trace[136726738] 'read index received' (duration: 67.868659ms)","trace[136726738] 'applied index is now lower than readState.Index' (duration: 153.275826ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:21:40.579236Z","caller":"traceutil/trace.go:171","msg":"trace[357936744] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"275.398223ms","start":"2026-04-07T00:21:40.303809Z","end":"2026-04-07T00:21:40.579207Z","steps":["trace[357936744] 'process raft request' (duration: 122.24446ms)","trace[357936744] 'compare' (duration: 152.516485ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:21:40.57932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.34599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5ntcl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T00:21:40.579377Z","caller":"traceutil/trace.go:171","msg":"trace[894268750] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5ntcl; range_end:; response_count:1; response_revision:8295; }","duration":"221.430323ms","start":"2026-04-07T00:21:40.357931Z","end":"2026-04-07T00:21:40.579362Z","steps":["trace[894268750] 'agreement among raft nodes before linearized reading' (duration: 221.291879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:40.579379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.958096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:21:40.57943Z","caller":"traceutil/trace.go:171","msg":"trace[1818821760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8295; }","duration":"157.089099ms","start":"2026-04-07T00:21:40.422326Z","end":"2026-04-07T00:21:40.579415Z","steps":["trace[1818821760] 'agreement among raft nodes before linearized reading' (duration: 156.998907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:41.413302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.041831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:21:41.413417Z","caller":"traceutil/trace.go:171","msg":"trace[1253451914] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8299; }","duration":"265.240506ms","start":"2026-04-07T00:21:41.148152Z","end":"2026-04-07T00:21:41.413392Z","steps":["trace[1253451914] 'count revisions from in-memory index tree' (duration: 264.890877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:21:41.413478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.160413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:21:41.41361Z","caller":"traceutil/trace.go:171","msg":"trace[319014478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8299; }","duration":"153.352198ms","start":"2026-04-07T00:21:41.260221Z","end":"2026-04-07T00:21:41.413573Z","steps":["trace[319014478] 'count revisions from in-memory index tree' (duration: 153.06933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:22.811085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.03823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:22:22.811364Z","caller":"traceutil/trace.go:171","msg":"trace[944863086] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8616; }","duration":"244.266155ms","start":"2026-04-07T00:22:22.567079Z","end":"2026-04-07T00:22:22.811345Z","steps":["trace[944863086] 'process raft request' (duration: 123.495884ms)","trace[944863086] 'compare' (duration: 119.857766ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:22.81514Z","caller":"traceutil/trace.go:171","msg":"trace[885528781] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"246.68295ms","start":"2026-04-07T00:22:22.568439Z","end":"2026-04-07T00:22:22.815121Z","steps":["trace[885528781] 'process raft request' (duration: 246.3085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:23.414895Z","caller":"traceutil/trace.go:171","msg":"trace[1839188817] transaction","detail":"{read_only:false; response_revision:8621; number_of_response:1; }","duration":"130.803153ms","start":"2026-04-07T00:22:23.28407Z","end":"2026-04-07T00:22:23.414873Z","steps":["trace[1839188817] 'process raft request' (duration: 130.657559ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:23.56086Z","caller":"traceutil/trace.go:171","msg":"trace[547255697] linearizableReadLoop","detail":"{readStateIndex:8933; appliedIndex:8932; }","duration":"138.432771ms","start":"2026-04-07T00:22:23.42241Z","end":"2026-04-07T00:22:23.560843Z","steps":["trace[547255697] 'read index received' (duration: 95.82059ms)","trace[547255697] 'applied index is now lower than readState.Index' (duration: 42.611531ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:23.560923Z","caller":"traceutil/trace.go:171","msg":"trace[102285070] transaction","detail":"{read_only:false; response_revision:8622; number_of_response:1; }","duration":"199.788923ms","start":"2026-04-07T00:22:23.361105Z","end":"2026-04-07T00:22:23.560894Z","steps":["trace[102285070] 'process raft request' (duration: 157.089909ms)","trace[102285070] 'compare' (duration: 42.490998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:22:23.56096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.536304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:22:23.560987Z","caller":"traceutil/trace.go:171","msg":"trace[1426882967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8622; }","duration":"138.596296ms","start":"2026-04-07T00:22:23.422384Z","end":"2026-04-07T00:22:23.56098Z","steps":["trace[1426882967] 'agreement among raft nodes before linearized reading' (duration: 138.538514ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:23.660045Z","caller":"traceutil/trace.go:171","msg":"trace[459189471] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"118.648323ms","start":"2026-04-07T00:22:23.541356Z","end":"2026-04-07T00:22:23.660005Z","steps":["trace[459189471] 'process raft request' (duration: 118.038906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:23.660244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.336143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-sb2pc\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-04-07T00:22:23.660346Z","caller":"traceutil/trace.go:171","msg":"trace[487285040] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-sb2pc; range_end:; response_count:1; response_revision:8624; }","duration":"129.59626ms","start":"2026-04-07T00:22:23.530729Z","end":"2026-04-07T00:22:23.660325Z","steps":["trace[487285040] 'agreement among raft nodes before linearized reading' (duration: 129.190299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:23.690179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.922078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-07T00:22:23.690269Z","caller":"traceutil/trace.go:171","msg":"trace[1434260073] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8624; }","duration":"156.062722ms","start":"2026-04-07T00:22:23.534185Z","end":"2026-04-07T00:22:23.690247Z","steps":["trace[1434260073] 'agreement among raft nodes before linearized reading' (duration: 126.2605ms)","trace[1434260073] 'range keys from in-memory index tree' (duration: 29.573666ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:24.52491Z","caller":"traceutil/trace.go:171","msg":"trace[895628478] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"432.284727ms","start":"2026-04-07T00:22:24.0926Z","end":"2026-04-07T00:22:24.524885Z","steps":["trace[895628478] 'process raft request' (duration: 432.121903ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:24.524969Z","caller":"traceutil/trace.go:171","msg":"trace[823716903] linearizableReadLoop","detail":"{readStateIndex:8938; appliedIndex:8938; }","duration":"422.650395ms","start":"2026-04-07T00:22:24.102248Z","end":"2026-04-07T00:22:24.524898Z","steps":["trace[823716903] 'read index received' (duration: 422.635284ms)","trace[823716903] 'applied index is now lower than readState.Index' (duration: 11.66µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:22:24.525063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:22:24.092582Z","time spent":"432.40514ms","remote":"127.0.0.1:52866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:22:24.525134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.86024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:22:24.525206Z","caller":"traceutil/trace.go:171","msg":"trace[563013233] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8627; }","duration":"422.967193ms","start":"2026-04-07T00:22:24.102221Z","end":"2026-04-07T00:22:24.525188Z","steps":["trace[563013233] 'agreement among raft nodes before linearized reading' (duration: 422.763358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:24.525244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:22:24.102209Z","time spent":"423.028135ms","remote":"127.0.0.1:36970","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":"warn","ts":"2026-04-07T00:22:24.525232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.288057ms","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":"warn","ts":"2026-04-07T00:22:24.52526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.850796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T00:22:24.525286Z","caller":"traceutil/trace.go:171","msg":"trace[2040890817] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8627; }","duration":"149.38906ms","start":"2026-04-07T00:22:24.375881Z","end":"2026-04-07T00:22:24.52527Z","steps":["trace[2040890817] 'agreement among raft nodes before linearized reading' (duration: 149.284807ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:24.52531Z","caller":"traceutil/trace.go:171","msg":"trace[1172116486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8627; }","duration":"155.951698ms","start":"2026-04-07T00:22:24.369344Z","end":"2026-04-07T00:22:24.525295Z","steps":["trace[1172116486] 'agreement among raft nodes before linearized reading' (duration: 155.755723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:24.525591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.109417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:22:24.525725Z","caller":"traceutil/trace.go:171","msg":"trace[923018553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8627; }","duration":"151.288191ms","start":"2026-04-07T00:22:24.374411Z","end":"2026-04-07T00:22:24.525699Z","steps":["trace[923018553] 'agreement among raft nodes before linearized reading' (duration: 151.042745ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:22:38.374517Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a6.wal"} {"level":"info","ts":"2026-04-07T00:23:35.411227Z","caller":"traceutil/trace.go:171","msg":"trace[1229406409] transaction","detail":"{read_only:false; response_revision:9340; number_of_response:1; }","duration":"214.690311ms","start":"2026-04-07T00:23:35.196505Z","end":"2026-04-07T00:23:35.411196Z","steps":["trace[1229406409] 'process raft request' (duration: 214.504706ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:35.493294Z","caller":"traceutil/trace.go:171","msg":"trace[2039138772] linearizableReadLoop","detail":"{readStateIndex:9671; appliedIndex:9669; }","duration":"182.489453ms","start":"2026-04-07T00:23:35.310785Z","end":"2026-04-07T00:23:35.493274Z","steps":["trace[2039138772] 'read index received' (duration: 100.297113ms)","trace[2039138772] 'applied index is now lower than readState.Index' (duration: 82.19143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:23:35.493471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.680109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5ntcl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-07T00:23:35.493519Z","caller":"traceutil/trace.go:171","msg":"trace[2049773013] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5ntcl; range_end:; response_count:1; response_revision:9342; }","duration":"182.787071ms","start":"2026-04-07T00:23:35.31072Z","end":"2026-04-07T00:23:35.493507Z","steps":["trace[2049773013] 'agreement among raft nodes before linearized reading' (duration: 182.598906ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:35.49353Z","caller":"traceutil/trace.go:171","msg":"trace[1687988181] transaction","detail":"{read_only:false; response_revision:9341; number_of_response:1; }","duration":"204.542754ms","start":"2026-04-07T00:23:35.288921Z","end":"2026-04-07T00:23:35.493464Z","steps":["trace[1687988181] 'process raft request' (duration: 165.377437ms)","trace[1687988181] 'compare' (duration: 38.842938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:23:35.493627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.870841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-74dbdc8947-kdk7j.18a3eb33afd1e415\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-07T00:23:35.493658Z","caller":"traceutil/trace.go:171","msg":"trace[1752919281] transaction","detail":"{read_only:false; response_revision:9342; number_of_response:1; }","duration":"150.439189ms","start":"2026-04-07T00:23:35.343185Z","end":"2026-04-07T00:23:35.493625Z","steps":["trace[1752919281] 'process raft request' (duration: 150.046849ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:35.493703Z","caller":"traceutil/trace.go:171","msg":"trace[381508193] range","detail":"{range_begin:/registry/events/openstack/barbican-api-74dbdc8947-kdk7j.18a3eb33afd1e415; range_end:; response_count:1; response_revision:9342; }","duration":"150.974194ms","start":"2026-04-07T00:23:35.342709Z","end":"2026-04-07T00:23:35.493683Z","steps":["trace[381508193] 'agreement among raft nodes before linearized reading' (duration: 150.777539ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:19.161964Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d2c3f297b56db0c4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:24:19.188913Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:24:19.18901Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:26:34.742774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8263} {"level":"info","ts":"2026-04-07T00:26:34.820059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8263,"took":"75.197882ms","hash":2209345864,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":26161152,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T00:26:34.820118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2209345864,"revision":8263,"compact-revision":6039} {"level":"info","ts":"2026-04-07T00:26:56.335943Z","caller":"traceutil/trace.go:171","msg":"trace[733461364] transaction","detail":"{read_only:false; response_revision:11060; number_of_response:1; }","duration":"145.624814ms","start":"2026-04-07T00:26:56.190294Z","end":"2026-04-07T00:26:56.335919Z","steps":["trace[733461364] 'process raft request' (duration: 145.46052ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:26:56.337194Z","caller":"traceutil/trace.go:171","msg":"trace[781713227] transaction","detail":"{read_only:false; response_revision:11061; number_of_response:1; }","duration":"144.610636ms","start":"2026-04-07T00:26:56.192556Z","end":"2026-04-07T00:26:56.337167Z","steps":["trace[781713227] 'process raft request' (duration: 144.272307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:09.9242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478704354759943,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:27:10.25065Z","caller":"traceutil/trace.go:171","msg":"trace[534268987] transaction","detail":"{read_only:false; response_revision:11135; number_of_response:1; }","duration":"905.633366ms","start":"2026-04-07T00:27:09.344988Z","end":"2026-04-07T00:27:10.250622Z","steps":["trace[534268987] 'process raft request' (duration: 905.154023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:10.250805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:27:09.344957Z","time spent":"905.768219ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415205,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:27:10.274607Z","caller":"traceutil/trace.go:171","msg":"trace[484732516] linearizableReadLoop","detail":"{readStateIndex:11519; appliedIndex:11517; }","duration":"851.160889ms","start":"2026-04-07T00:27:09.423416Z","end":"2026-04-07T00:27:10.274577Z","steps":["trace[484732516] 'read index received' (duration: 826.767263ms)","trace[484732516] 'applied index is now lower than readState.Index' (duration: 24.392526ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:27:10.274688Z","caller":"traceutil/trace.go:171","msg":"trace[271322365] transaction","detail":"{read_only:false; response_revision:11136; number_of_response:1; }","duration":"360.285452ms","start":"2026-04-07T00:27:09.914345Z","end":"2026-04-07T00:27:10.27463Z","steps":["trace[271322365] 'process raft request' (duration: 360.069586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:10.274933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:27:09.914319Z","time spent":"360.497858ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:27:10.274948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.503699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:27:10.275047Z","caller":"traceutil/trace.go:171","msg":"trace[1844794834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11136; }","duration":"851.672063ms","start":"2026-04-07T00:27:09.42336Z","end":"2026-04-07T00:27:10.275032Z","steps":["trace[1844794834] 'agreement among raft nodes before linearized reading' (duration: 851.359215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:10.275106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:27:09.423337Z","time spent":"851.758545ms","remote":"127.0.0.1:36712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:27:10.27509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.22007ms","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-04-07T00:27:10.275205Z","caller":"traceutil/trace.go:171","msg":"trace[1014562575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11136; }","duration":"209.392244ms","start":"2026-04-07T00:27:10.065789Z","end":"2026-04-07T00:27:10.275182Z","steps":["trace[1014562575] 'agreement among raft nodes before linearized reading' (duration: 209.050285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:10.275342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.265515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:27:10.275394Z","caller":"traceutil/trace.go:171","msg":"trace[700390734] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:11136; }","duration":"152.344467ms","start":"2026-04-07T00:27:10.123038Z","end":"2026-04-07T00:27:10.275382Z","steps":["trace[700390734] 'agreement among raft nodes before linearized reading' (duration: 152.256534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:10.275294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.687781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:27:10.275466Z","caller":"traceutil/trace.go:171","msg":"trace[1454124239] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11136; }","duration":"158.892956ms","start":"2026-04-07T00:27:10.116545Z","end":"2026-04-07T00:27:10.275438Z","steps":["trace[1454124239] 'agreement among raft nodes before linearized reading' (duration: 158.314771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:27:10.275465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.530618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T00:27:10.275471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.304803ms","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-04-07T00:27:10.275534Z","caller":"traceutil/trace.go:171","msg":"trace[364030097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11136; }","duration":"209.654072ms","start":"2026-04-07T00:27:10.065866Z","end":"2026-04-07T00:27:10.27552Z","steps":["trace[364030097] 'agreement among raft nodes before linearized reading' (duration: 209.469327ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:27:10.275548Z","caller":"traceutil/trace.go:171","msg":"trace[1734373502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11136; }","duration":"205.428587ms","start":"2026-04-07T00:27:10.070101Z","end":"2026-04-07T00:27:10.27553Z","steps":["trace[1734373502] 'agreement among raft nodes before linearized reading' (duration: 205.198431ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:31:34.754177Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10759} {"level":"info","ts":"2026-04-07T00:31:34.837095Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10759,"took":"80.434356ms","hash":3335248342,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":27394048,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-07T00:31:34.83717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3335248342,"revision":10759,"compact-revision":8263} {"level":"info","ts":"2026-04-07T00:33:35.977154Z","caller":"traceutil/trace.go:171","msg":"trace[2091634049] transaction","detail":"{read_only:false; response_revision:13736; number_of_response:1; }","duration":"243.027712ms","start":"2026-04-07T00:33:35.734103Z","end":"2026-04-07T00:33:35.977131Z","steps":["trace[2091634049] 'process raft request' (duration: 242.875228ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:33:35.978549Z","caller":"traceutil/trace.go:171","msg":"trace[1252954378] linearizableReadLoop","detail":"{readStateIndex:14211; appliedIndex:14209; }","duration":"241.378656ms","start":"2026-04-07T00:33:35.737158Z","end":"2026-04-07T00:33:35.978537Z","steps":["trace[1252954378] 'read index received' (duration: 239.837644ms)","trace[1252954378] 'applied index is now lower than readState.Index' (duration: 1.540062ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:33:35.978712Z","caller":"traceutil/trace.go:171","msg":"trace[547979537] transaction","detail":"{read_only:false; response_revision:13737; number_of_response:1; }","duration":"243.281109ms","start":"2026-04-07T00:33:35.735421Z","end":"2026-04-07T00:33:35.978702Z","steps":["trace[547979537] 'process raft request' (duration: 242.994101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:35.97918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.005964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-07T00:33:35.97928Z","caller":"traceutil/trace.go:171","msg":"trace[755133342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13738; }","duration":"242.135747ms","start":"2026-04-07T00:33:35.737117Z","end":"2026-04-07T00:33:35.979253Z","steps":["trace[755133342] 'agreement among raft nodes before linearized reading' (duration: 241.89342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:35.979958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.182032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929859"} {"level":"info","ts":"2026-04-07T00:33:35.980055Z","caller":"traceutil/trace.go:171","msg":"trace[949005557] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13738; }","duration":"230.321247ms","start":"2026-04-07T00:33:35.749719Z","end":"2026-04-07T00:33:35.980041Z","steps":["trace[949005557] 'agreement among raft nodes before linearized reading' (duration: 229.309299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:33:36.19Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.14323ms","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-04-07T00:33:36.190113Z","caller":"traceutil/trace.go:171","msg":"trace[1315359990] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13738; }","duration":"134.312285ms","start":"2026-04-07T00:33:36.05578Z","end":"2026-04-07T00:33:36.190092Z","steps":["trace[1315359990] 'range keys from in-memory index tree' (duration: 134.045908ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:34.760665Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12928} {"level":"info","ts":"2026-04-07T00:36:34.837846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12928,"took":"75.088318ms","hash":3804603289,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":31309824,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T00:36:34.837924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804603289,"revision":12928,"compact-revision":10759} {"level":"info","ts":"2026-04-07T00:41:01.273405Z","caller":"traceutil/trace.go:171","msg":"trace[821892879] transaction","detail":"{read_only:false; response_revision:17714; number_of_response:1; }","duration":"204.028135ms","start":"2026-04-07T00:41:01.069333Z","end":"2026-04-07T00:41:01.273361Z","steps":["trace[821892879] 'process raft request' (duration: 203.82977ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:01.459932Z","caller":"traceutil/trace.go:171","msg":"trace[1789843860] transaction","detail":"{read_only:false; response_revision:17715; number_of_response:1; }","duration":"177.315506ms","start":"2026-04-07T00:41:01.28258Z","end":"2026-04-07T00:41:01.459895Z","steps":["trace[1789843860] 'process raft request' (duration: 169.870293ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:41:08.799472Z","caller":"traceutil/trace.go:171","msg":"trace[1602215134] transaction","detail":"{read_only:false; response_revision:17789; number_of_response:1; }","duration":"100.877562ms","start":"2026-04-07T00:41:08.698571Z","end":"2026-04-07T00:41:08.799449Z","steps":["trace[1602215134] 'process raft request' (duration: 90.349724ms)","trace[1602215134] 'compare' (duration: 10.278051ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:41:34.765271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15469} {"level":"info","ts":"2026-04-07T00:41:34.859268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15469,"took":"91.428354ms","hash":2245852189,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":33497088,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:41:34.85936Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2245852189,"revision":15469,"compact-revision":12928} {"level":"warn","ts":"2026-04-07T00:42:51.564133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.337014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:42:51.564229Z","caller":"traceutil/trace.go:171","msg":"trace[902942914] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:18613; }","duration":"112.586501ms","start":"2026-04-07T00:42:51.451625Z","end":"2026-04-07T00:42:51.564211Z","steps":["trace[902942914] 'count revisions from in-memory index tree' (duration: 112.1671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:51.564504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.397054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:42:51.564695Z","caller":"traceutil/trace.go:171","msg":"trace[525523142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18613; }","duration":"142.578998ms","start":"2026-04-07T00:42:51.422008Z","end":"2026-04-07T00:42:51.564587Z","steps":["trace[525523142] 'range keys from in-memory index tree' (duration: 142.25444ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:44:26.257275Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d2c3f297b56db0c4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:44:26.263145Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-07T00:44:26.263264Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-07T00:45:38.947186Z","caller":"traceutil/trace.go:171","msg":"trace[1889695383] transaction","detail":"{read_only:false; response_revision:19782; number_of_response:1; }","duration":"270.494426ms","start":"2026-04-07T00:45:38.676663Z","end":"2026-04-07T00:45:38.947157Z","steps":["trace[1889695383] 'process raft request' (duration: 241.627649ms)","trace[1889695383] 'compare' (duration: 28.647251ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:45:38.949878Z","caller":"traceutil/trace.go:171","msg":"trace[1024833690] transaction","detail":"{read_only:false; response_revision:19783; number_of_response:1; }","duration":"272.885612ms","start":"2026-04-07T00:45:38.676969Z","end":"2026-04-07T00:45:38.949855Z","steps":["trace[1024833690] 'process raft request' (duration: 272.722108ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:45:38.949963Z","caller":"traceutil/trace.go:171","msg":"trace[908407639] linearizableReadLoop","detail":"{readStateIndex:20451; appliedIndex:20448; }","duration":"160.996681ms","start":"2026-04-07T00:45:38.788945Z","end":"2026-04-07T00:45:38.949941Z","steps":["trace[908407639] 'read index received' (duration: 129.306077ms)","trace[908407639] 'applied index is now lower than readState.Index' (duration: 31.689284ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:45:38.950026Z","caller":"traceutil/trace.go:171","msg":"trace[1967199460] transaction","detail":"{read_only:false; response_revision:19784; number_of_response:1; }","duration":"175.668422ms","start":"2026-04-07T00:45:38.774324Z","end":"2026-04-07T00:45:38.949993Z","steps":["trace[1967199460] 'process raft request' (duration: 175.490067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:45:38.950062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.093904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:45:38.950156Z","caller":"traceutil/trace.go:171","msg":"trace[960298571] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:19784; }","duration":"161.245108ms","start":"2026-04-07T00:45:38.7889Z","end":"2026-04-07T00:45:38.950145Z","steps":["trace[960298571] 'agreement among raft nodes before linearized reading' (duration: 161.098344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:45:50.156885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.152699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:45:50.157009Z","caller":"traceutil/trace.go:171","msg":"trace[464932959] linearizableReadLoop","detail":"{readStateIndex:20504; appliedIndex:20503; }","duration":"128.047973ms","start":"2026-04-07T00:45:50.02894Z","end":"2026-04-07T00:45:50.156988Z","steps":["trace[464932959] 'read index received' (duration: 34.031µs)","trace[464932959] 'applied index is now lower than readState.Index' (duration: 128.012142ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:45:50.157304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.34323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T00:45:50.157356Z","caller":"traceutil/trace.go:171","msg":"trace[707430529] transaction","detail":"{read_only:false; response_revision:19835; number_of_response:1; }","duration":"286.539495ms","start":"2026-04-07T00:45:49.870802Z","end":"2026-04-07T00:45:50.157342Z","steps":["trace[707430529] 'process raft request' (duration: 92.830422ms)","trace[707430529] 'compare' (duration: 192.974953ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:45:50.157375Z","caller":"traceutil/trace.go:171","msg":"trace[561844758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19835; }","duration":"128.450694ms","start":"2026-04-07T00:45:50.028906Z","end":"2026-04-07T00:45:50.157357Z","steps":["trace[561844758] 'agreement among raft nodes before linearized reading' (duration: 128.230848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:45:50.157509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.432313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-07T00:45:50.157568Z","caller":"traceutil/trace.go:171","msg":"trace[769361309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19835; }","duration":"128.558587ms","start":"2026-04-07T00:45:50.028994Z","end":"2026-04-07T00:45:50.157552Z","steps":["trace[769361309] 'agreement among raft nodes before linearized reading' (duration: 128.367392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:45:50.157572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.874162ms","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-04-07T00:45:50.157642Z","caller":"traceutil/trace.go:171","msg":"trace[1071917818] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19835; }","duration":"122.966644ms","start":"2026-04-07T00:45:50.034658Z","end":"2026-04-07T00:45:50.157625Z","steps":["trace[1071917818] 'agreement among raft nodes before linearized reading' (duration: 122.712047ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:45:50.476045Z","caller":"traceutil/trace.go:171","msg":"trace[11067828] transaction","detail":"{read_only:false; response_revision:19837; number_of_response:1; }","duration":"314.336694ms","start":"2026-04-07T00:45:50.161682Z","end":"2026-04-07T00:45:50.476018Z","steps":["trace[11067828] 'process raft request' (duration: 314.098747ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:45:50.477146Z","caller":"traceutil/trace.go:171","msg":"trace[1897324046] linearizableReadLoop","detail":"{readStateIndex:20507; appliedIndex:20505; }","duration":"231.623008ms","start":"2026-04-07T00:45:50.245399Z","end":"2026-04-07T00:45:50.477022Z","steps":["trace[1897324046] 'read index received' (duration: 230.358344ms)","trace[1897324046] 'applied index is now lower than readState.Index' (duration: 1.260594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:45:50.477223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:45:50.161667Z","time spent":"315.243119ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:45:50.477326Z","caller":"traceutil/trace.go:171","msg":"trace[1831024198] transaction","detail":"{read_only:false; response_revision:19838; number_of_response:1; }","duration":"315.529706ms","start":"2026-04-07T00:45:50.16172Z","end":"2026-04-07T00:45:50.47725Z","steps":["trace[1831024198] 'process raft request' (duration: 314.249461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:45:50.477571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:45:50.161703Z","time spent":"315.758813ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:45:50.477925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.497001ms","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-04-07T00:45:50.478086Z","caller":"traceutil/trace.go:171","msg":"trace[851937970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19838; }","duration":"232.704167ms","start":"2026-04-07T00:45:50.245329Z","end":"2026-04-07T00:45:50.478033Z","steps":["trace[851937970] 'agreement among raft nodes before linearized reading' (duration: 232.031169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:45:50.816917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.815444ms","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-04-07T00:45:50.817005Z","caller":"traceutil/trace.go:171","msg":"trace[1923810342] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19839; }","duration":"179.947988ms","start":"2026-04-07T00:45:50.637037Z","end":"2026-04-07T00:45:50.816985Z","steps":["trace[1923810342] 'range keys from in-memory index tree' (duration: 179.723082ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:46:34.771956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18043} {"level":"info","ts":"2026-04-07T00:46:34.879793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18043,"took":"104.398417ms","hash":4107617743,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":30294016,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T00:46:34.87985Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4107617743,"revision":18043,"compact-revision":15469} {"level":"info","ts":"2026-04-07T00:51:34.779257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20330} {"level":"info","ts":"2026-04-07T00:51:34.888121Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20330,"took":"103.343418ms","hash":257737523,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":42328064,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-07T00:51:34.888188Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":257737523,"revision":20330,"compact-revision":18043} {"level":"info","ts":"2026-04-07T00:52:38.040684Z","caller":"traceutil/trace.go:171","msg":"trace[715690590] transaction","detail":"{read_only:false; response_revision:24116; number_of_response:1; }","duration":"170.101529ms","start":"2026-04-07T00:52:37.870558Z","end":"2026-04-07T00:52:38.04066Z","steps":["trace[715690590] 'process raft request' (duration: 127.700462ms)","trace[715690590] 'compare' (duration: 41.855712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:43.146896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.816958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:52:43.147092Z","caller":"traceutil/trace.go:171","msg":"trace[1405431820] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24162; }","duration":"105.072945ms","start":"2026-04-07T00:52:43.041985Z","end":"2026-04-07T00:52:43.147058Z","steps":["trace[1405431820] 'count revisions from in-memory index tree' (duration: 104.702905ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:43.30069Z","caller":"traceutil/trace.go:171","msg":"trace[286280405] linearizableReadLoop","detail":"{readStateIndex:24957; appliedIndex:24956; }","duration":"159.548622ms","start":"2026-04-07T00:52:43.141114Z","end":"2026-04-07T00:52:43.300662Z","steps":["trace[286280405] 'read index received' (duration: 159.227993ms)","trace[286280405] 'applied index is now lower than readState.Index' (duration: 319.308µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:43.300804Z","caller":"traceutil/trace.go:171","msg":"trace[936506398] transaction","detail":"{read_only:false; response_revision:24163; number_of_response:1; }","duration":"253.72523ms","start":"2026-04-07T00:52:43.047042Z","end":"2026-04-07T00:52:43.300768Z","steps":["trace[936506398] 'process raft request' (duration: 253.393171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:43.301153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.007784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-04-07T00:52:43.30129Z","caller":"traceutil/trace.go:171","msg":"trace[2123722732] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24163; }","duration":"160.202829ms","start":"2026-04-07T00:52:43.141069Z","end":"2026-04-07T00:52:43.301272Z","steps":["trace[2123722732] 'agreement among raft nodes before linearized reading' (duration: 159.736556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:43.550963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.495935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:52:43.551152Z","caller":"traceutil/trace.go:171","msg":"trace[1808605821] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24163; }","duration":"102.720662ms","start":"2026-04-07T00:52:43.448389Z","end":"2026-04-07T00:52:43.55111Z","steps":["trace[1808605821] 'count revisions from in-memory index tree' (duration: 102.407313ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:44.079257Z","caller":"traceutil/trace.go:171","msg":"trace[2037503194] transaction","detail":"{read_only:false; response_revision:24164; number_of_response:1; }","duration":"136.03521ms","start":"2026-04-07T00:52:43.943192Z","end":"2026-04-07T00:52:44.079227Z","steps":["trace[2037503194] 'process raft request' (duration: 135.781943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.322569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.396328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:52:44.322902Z","caller":"traceutil/trace.go:171","msg":"trace[628805377] transaction","detail":"{read_only:false; response_revision:24165; number_of_response:1; }","duration":"217.967095ms","start":"2026-04-07T00:52:44.104913Z","end":"2026-04-07T00:52:44.32288Z","steps":["trace[628805377] 'process raft request' (duration: 62.179796ms)","trace[628805377] 'compare' (duration: 155.284135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:44.733656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.066507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:52:44.733874Z","caller":"traceutil/trace.go:171","msg":"trace[1791765064] transaction","detail":"{read_only:false; response_revision:24167; number_of_response:1; }","duration":"384.460084ms","start":"2026-04-07T00:52:44.349394Z","end":"2026-04-07T00:52:44.733854Z","steps":["trace[1791765064] 'process raft request' (duration: 384.376432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.733943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.34937Z","time spent":"384.538366ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:44.734112Z","caller":"traceutil/trace.go:171","msg":"trace[1247409084] linearizableReadLoop","detail":"{readStateIndex:24960; appliedIndex:24959; }","duration":"399.877464ms","start":"2026-04-07T00:52:44.334176Z","end":"2026-04-07T00:52:44.734053Z","steps":["trace[1247409084] 'read index received' (duration: 141.334944ms)","trace[1247409084] 'applied index is now lower than readState.Index' (duration: 258.53932ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:44.734311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.110671ms","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-04-07T00:52:44.734374Z","caller":"traceutil/trace.go:171","msg":"trace[1785463619] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24167; }","duration":"400.206074ms","start":"2026-04-07T00:52:44.334149Z","end":"2026-04-07T00:52:44.734355Z","steps":["trace[1785463619] 'agreement among raft nodes before linearized reading' (duration: 400.07747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.734427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.33414Z","time spent":"400.274225ms","remote":"127.0.0.1:36980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-04-07T00:52:44.73453Z","caller":"traceutil/trace.go:171","msg":"trace[130318128] transaction","detail":"{read_only:false; response_revision:24166; number_of_response:1; }","duration":"402.775424ms","start":"2026-04-07T00:52:44.331074Z","end":"2026-04-07T00:52:44.733849Z","steps":["trace[130318128] 'process raft request' (duration: 144.418479ms)","trace[130318128] 'compare' (duration: 257.689667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:44.734837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.331061Z","time spent":"403.531314ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:44.997644Z","caller":"traceutil/trace.go:171","msg":"trace[1455059998] transaction","detail":"{read_only:false; response_revision:24170; number_of_response:1; }","duration":"428.3283ms","start":"2026-04-07T00:52:44.569288Z","end":"2026-04-07T00:52:44.997617Z","steps":["trace[1455059998] 'process raft request' (duration: 428.269389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.997891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.659001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:52:44.997941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.278819ms","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-04-07T00:52:44.997955Z","caller":"traceutil/trace.go:171","msg":"trace[1736575640] transaction","detail":"{read_only:false; response_revision:24171; number_of_response:1; }","duration":"409.746764ms","start":"2026-04-07T00:52:44.588194Z","end":"2026-04-07T00:52:44.99794Z","steps":["trace[1736575640] 'process raft request' (duration: 409.393685ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:44.998006Z","caller":"traceutil/trace.go:171","msg":"trace[757017596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24171; }","duration":"637.378562ms","start":"2026-04-07T00:52:44.360615Z","end":"2026-04-07T00:52:44.997993Z","steps":["trace[757017596] 'agreement among raft nodes before linearized reading' (duration: 637.186616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.998048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.414565ms","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-04-07T00:52:44.998016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.588417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:52:44.998085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.058217ms","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-04-07T00:52:44.998073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.588165Z","time spent":"409.856046ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:44.998106Z","caller":"traceutil/trace.go:171","msg":"trace[1383082077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24171; }","duration":"431.494607ms","start":"2026-04-07T00:52:44.566598Z","end":"2026-04-07T00:52:44.998092Z","steps":["trace[1383082077] 'agreement among raft nodes before linearized reading' (duration: 431.369384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.998115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.278477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-04-07T00:52:44.998151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.566581Z","time spent":"431.560839ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T00:52:44.998127Z","caller":"traceutil/trace.go:171","msg":"trace[335580490] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24171; }","duration":"401.126959ms","start":"2026-04-07T00:52:44.596988Z","end":"2026-04-07T00:52:44.998115Z","steps":["trace[335580490] 'agreement among raft nodes before linearized reading' (duration: 400.993185ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:44.998143Z","caller":"traceutil/trace.go:171","msg":"trace[548575358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24171; }","duration":"575.761592ms","start":"2026-04-07T00:52:44.422351Z","end":"2026-04-07T00:52:44.998113Z","steps":["trace[548575358] 'agreement among raft nodes before linearized reading' (duration: 575.550416ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:44.998174Z","caller":"traceutil/trace.go:171","msg":"trace[239157034] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24171; }","duration":"605.376809ms","start":"2026-04-07T00:52:44.392781Z","end":"2026-04-07T00:52:44.998157Z","steps":["trace[239157034] 'agreement among raft nodes before linearized reading' (duration: 605.183114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.998208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.596975Z","time spent":"401.222571ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:52:44.99822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.392761Z","time spent":"605.447011ms","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T00:52:44.998232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.422335Z","time spent":"575.878855ms","remote":"127.0.0.1:36712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:52:44.997897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.999049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:52:44.998287Z","caller":"traceutil/trace.go:171","msg":"trace[60978116] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24171; }","duration":"392.422021ms","start":"2026-04-07T00:52:44.60585Z","end":"2026-04-07T00:52:44.998272Z","steps":["trace[60978116] 'agreement among raft nodes before linearized reading' (duration: 391.917808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.998251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.222797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-07T00:52:44.998326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.605837Z","time spent":"392.478923ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:52:44.997676Z","caller":"traceutil/trace.go:171","msg":"trace[543703208] transaction","detail":"{read_only:false; response_revision:24168; number_of_response:1; }","duration":"445.851499ms","start":"2026-04-07T00:52:44.551779Z","end":"2026-04-07T00:52:44.997631Z","steps":["trace[543703208] 'process raft request' (duration: 445.557391ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:44.998404Z","caller":"traceutil/trace.go:171","msg":"trace[1552614297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24171; }","duration":"395.432273ms","start":"2026-04-07T00:52:44.602902Z","end":"2026-04-07T00:52:44.998334Z","steps":["trace[1552614297] 'agreement among raft nodes before linearized reading' (duration: 395.022452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.997926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.654625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:52:44.998486Z","caller":"traceutil/trace.go:171","msg":"trace[1876329415] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:24171; }","duration":"504.265172ms","start":"2026-04-07T00:52:44.494199Z","end":"2026-04-07T00:52:44.998464Z","steps":["trace[1876329415] 'agreement among raft nodes before linearized reading' (duration: 503.571083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.9985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.602888Z","time spent":"395.593847ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:52:44.998501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.569266Z","time spent":"428.592338ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:44.998487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.551754Z","time spent":"446.66352ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:44.998563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.494181Z","time spent":"504.359605ms","remote":"127.0.0.1:46364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":32,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:52:44.997956Z","caller":"traceutil/trace.go:171","msg":"trace[1011372374] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24171; }","duration":"420.767434ms","start":"2026-04-07T00:52:44.577174Z","end":"2026-04-07T00:52:44.997942Z","steps":["trace[1011372374] 'agreement among raft nodes before linearized reading' (duration: 420.583979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.998052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.360598Z","time spent":"637.441364ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T00:52:44.9977Z","caller":"traceutil/trace.go:171","msg":"trace[1754444924] transaction","detail":"{read_only:false; response_revision:24169; number_of_response:1; }","duration":"443.165735ms","start":"2026-04-07T00:52:44.554489Z","end":"2026-04-07T00:52:44.997655Z","steps":["trace[1754444924] 'process raft request' (duration: 443.026762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:44.999115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.554481Z","time spent":"444.582224ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:44.997763Z","caller":"traceutil/trace.go:171","msg":"trace[627728592] linearizableReadLoop","detail":"{readStateIndex:24965; appliedIndex:24961; }","duration":"263.536398ms","start":"2026-04-07T00:52:44.734192Z","end":"2026-04-07T00:52:44.997729Z","steps":["trace[627728592] 'read index received' (duration: 263.012533ms)","trace[627728592] 'applied index is now lower than readState.Index' (duration: 521.884µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:44.998885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:44.577161Z","time spent":"421.600847ms","remote":"127.0.0.1:60938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:52:44.999328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.939069ms","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-04-07T00:52:45.000479Z","caller":"traceutil/trace.go:171","msg":"trace[1931236745] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24171; }","duration":"261.014458ms","start":"2026-04-07T00:52:44.73935Z","end":"2026-04-07T00:52:45.000364Z","steps":["trace[1931236745] 'agreement among raft nodes before linearized reading' (duration: 259.930899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:45.413416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.554081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:52:45.413513Z","caller":"traceutil/trace.go:171","msg":"trace[938618932] linearizableReadLoop","detail":"{readStateIndex:24966; appliedIndex:24965; }","duration":"409.949281ms","start":"2026-04-07T00:52:45.003547Z","end":"2026-04-07T00:52:45.413496Z","steps":["trace[938618932] 'read index received' (duration: 151.183194ms)","trace[938618932] 'applied index is now lower than readState.Index' (duration: 258.764807ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:45.413537Z","caller":"traceutil/trace.go:171","msg":"trace[841448498] transaction","detail":"{read_only:false; response_revision:24172; number_of_response:1; }","duration":"410.168386ms","start":"2026-04-07T00:52:45.003336Z","end":"2026-04-07T00:52:45.413505Z","steps":["trace[841448498] 'process raft request' (duration: 151.44902ms)","trace[841448498] 'compare' (duration: 258.294514ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:45.413647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:45.003315Z","time spent":"410.273719ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:45.413664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.112855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-04-07T00:52:45.413695Z","caller":"traceutil/trace.go:171","msg":"trace[627499278] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24172; }","duration":"410.173867ms","start":"2026-04-07T00:52:45.003512Z","end":"2026-04-07T00:52:45.413686Z","steps":["trace[627499278] 'agreement among raft nodes before linearized reading' (duration: 410.027933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:45.413737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:45.003496Z","time spent":"410.226208ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18595,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T00:52:45.723915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.485316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:52:45.724101Z","caller":"traceutil/trace.go:171","msg":"trace[186603875] linearizableReadLoop","detail":"{readStateIndex:24970; appliedIndex:24966; }","duration":"205.804413ms","start":"2026-04-07T00:52:45.518285Z","end":"2026-04-07T00:52:45.72409Z","steps":["trace[186603875] 'read index received' (duration: 40.999249ms)","trace[186603875] 'applied index is now lower than readState.Index' (duration: 164.804564ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:45.72417Z","caller":"traceutil/trace.go:171","msg":"trace[1966815268] transaction","detail":"{read_only:false; response_revision:24176; number_of_response:1; }","duration":"719.393589ms","start":"2026-04-07T00:52:45.004769Z","end":"2026-04-07T00:52:45.724163Z","steps":["trace[1966815268] 'process raft request' (duration: 719.293037ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:45.724178Z","caller":"traceutil/trace.go:171","msg":"trace[1948127546] transaction","detail":"{read_only:false; response_revision:24175; number_of_response:1; }","duration":"719.388169ms","start":"2026-04-07T00:52:45.004774Z","end":"2026-04-07T00:52:45.724162Z","steps":["trace[1948127546] 'process raft request' (duration: 719.244955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:45.724214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:45.004751Z","time spent":"719.441801ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:45.724255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:45.00476Z","time spent":"719.454182ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:45.724311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.022319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:52:45.724329Z","caller":"traceutil/trace.go:171","msg":"trace[1028439333] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24176; }","duration":"206.079331ms","start":"2026-04-07T00:52:45.518244Z","end":"2026-04-07T00:52:45.724323Z","steps":["trace[1028439333] 'agreement among raft nodes before linearized reading' (duration: 206.04092ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:45.72435Z","caller":"traceutil/trace.go:171","msg":"trace[1579811785] transaction","detail":"{read_only:false; response_revision:24174; number_of_response:1; }","duration":"720.629793ms","start":"2026-04-07T00:52:45.00371Z","end":"2026-04-07T00:52:45.72434Z","steps":["trace[1579811785] 'process raft request' (duration: 720.270953ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:45.724374Z","caller":"traceutil/trace.go:171","msg":"trace[1874218500] transaction","detail":"{read_only:false; response_revision:24173; number_of_response:1; }","duration":"720.691455ms","start":"2026-04-07T00:52:45.003654Z","end":"2026-04-07T00:52:45.724346Z","steps":["trace[1874218500] 'process raft request' (duration: 555.728376ms)","trace[1874218500] 'compare' (duration: 164.359023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:45.724516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:45.003619Z","time spent":"720.826249ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:45.724534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.61842ms","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-04-07T00:52:45.72464Z","caller":"traceutil/trace.go:171","msg":"trace[872790654] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24176; }","duration":"186.761014ms","start":"2026-04-07T00:52:45.537868Z","end":"2026-04-07T00:52:45.724629Z","steps":["trace[872790654] 'agreement among raft nodes before linearized reading' (duration: 186.63166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:45.724693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.923933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-07T00:52:45.724717Z","caller":"traceutil/trace.go:171","msg":"trace[183314437] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24176; }","duration":"144.989824ms","start":"2026-04-07T00:52:45.579722Z","end":"2026-04-07T00:52:45.724712Z","steps":["trace[183314437] 'agreement among raft nodes before linearized reading' (duration: 144.8244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:45.725575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:45.003692Z","time spent":"720.683685ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:46.211551Z","caller":"traceutil/trace.go:171","msg":"trace[549365683] transaction","detail":"{read_only:false; response_revision:24177; number_of_response:1; }","duration":"126.88028ms","start":"2026-04-07T00:52:46.084638Z","end":"2026-04-07T00:52:46.211519Z","steps":["trace[549365683] 'process raft request' (duration: 126.694575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:46.367905Z","caller":"traceutil/trace.go:171","msg":"trace[1921454909] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"107.749109ms","start":"2026-04-07T00:52:46.260133Z","end":"2026-04-07T00:52:46.367882Z","steps":["trace[1921454909] 'process raft request' (duration: 107.581474ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:46.433142Z","caller":"traceutil/trace.go:171","msg":"trace[575161427] transaction","detail":"{read_only:false; response_revision:24180; number_of_response:1; }","duration":"103.338018ms","start":"2026-04-07T00:52:46.32978Z","end":"2026-04-07T00:52:46.433118Z","steps":["trace[575161427] 'process raft request' (duration: 103.168094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:46.433339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.071644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:52:46.433414Z","caller":"traceutil/trace.go:171","msg":"trace[321978687] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:24180; }","duration":"120.170548ms","start":"2026-04-07T00:52:46.313223Z","end":"2026-04-07T00:52:46.433394Z","steps":["trace[321978687] 'agreement among raft nodes before linearized reading' (duration: 120.019334ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:46.936657Z","caller":"traceutil/trace.go:171","msg":"trace[599257421] transaction","detail":"{read_only:false; response_revision:24182; number_of_response:1; }","duration":"195.150072ms","start":"2026-04-07T00:52:46.741446Z","end":"2026-04-07T00:52:46.936597Z","steps":["trace[599257421] 'process raft request' (duration: 181.50246ms)","trace[599257421] 'compare' (duration: 13.326373ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:47.19428Z","caller":"traceutil/trace.go:171","msg":"trace[7079046] transaction","detail":"{read_only:false; response_revision:24183; number_of_response:1; }","duration":"190.380522ms","start":"2026-04-07T00:52:47.003876Z","end":"2026-04-07T00:52:47.194257Z","steps":["trace[7079046] 'process raft request' (duration: 132.832002ms)","trace[7079046] 'compare' (duration: 57.261122ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:47.324451Z","caller":"traceutil/trace.go:171","msg":"trace[816479699] transaction","detail":"{read_only:false; response_revision:24185; number_of_response:1; }","duration":"319.072872ms","start":"2026-04-07T00:52:47.005319Z","end":"2026-04-07T00:52:47.324392Z","steps":["trace[816479699] 'process raft request' (duration: 319.00747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:47.32465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:47.005299Z","time spent":"319.273297ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:47.324766Z","caller":"traceutil/trace.go:171","msg":"trace[2121243184] transaction","detail":"{read_only:false; response_revision:24186; number_of_response:1; }","duration":"318.712452ms","start":"2026-04-07T00:52:47.006016Z","end":"2026-04-07T00:52:47.324729Z","steps":["trace[2121243184] 'process raft request' (duration: 318.347482ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:47.324989Z","caller":"traceutil/trace.go:171","msg":"trace[1742415647] transaction","detail":"{read_only:false; response_revision:24184; number_of_response:1; }","duration":"320.310455ms","start":"2026-04-07T00:52:47.004655Z","end":"2026-04-07T00:52:47.324966Z","steps":["trace[1742415647] 'process raft request' (duration: 298.296035ms)","trace[1742415647] 'compare' (duration: 21.25632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:47.325046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:47.005995Z","time spent":"318.933177ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:47.325059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:47.004633Z","time spent":"320.391088ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:47.624579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.447179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:52:47.624684Z","caller":"traceutil/trace.go:171","msg":"trace[76826568] transaction","detail":"{read_only:false; response_revision:24187; number_of_response:1; }","duration":"201.995999ms","start":"2026-04-07T00:52:47.422672Z","end":"2026-04-07T00:52:47.624668Z","steps":["trace[76826568] 'process raft request' (duration: 42.394316ms)","trace[76826568] 'compare' (duration: 159.032907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:47.939078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.616512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-04-07T00:52:47.939202Z","caller":"traceutil/trace.go:171","msg":"trace[1662360211] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:24187; }","duration":"167.803427ms","start":"2026-04-07T00:52:47.771368Z","end":"2026-04-07T00:52:47.939172Z","steps":["trace[1662360211] 'range keys from in-memory index tree' (duration: 167.317363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:47.93929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.268705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T00:52:47.939337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.311046ms","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-04-07T00:52:47.93936Z","caller":"traceutil/trace.go:171","msg":"trace[601974957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24187; }","duration":"210.384038ms","start":"2026-04-07T00:52:47.728959Z","end":"2026-04-07T00:52:47.939343Z","steps":["trace[601974957] 'range keys from in-memory index tree' (duration: 210.099761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:47.939341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.152412ms","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":"info","ts":"2026-04-07T00:52:47.93945Z","caller":"traceutil/trace.go:171","msg":"trace[1679014131] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24187; }","duration":"210.282096ms","start":"2026-04-07T00:52:47.729138Z","end":"2026-04-07T00:52:47.93942Z","steps":["trace[1679014131] 'range keys from in-memory index tree' (duration: 209.951956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:47.939334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.238274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:52:47.939542Z","caller":"traceutil/trace.go:171","msg":"trace[1021144129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24187; }","duration":"210.43625ms","start":"2026-04-07T00:52:47.728963Z","end":"2026-04-07T00:52:47.939399Z","steps":["trace[1021144129] 'range keys from in-memory index tree' (duration: 210.119632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:47.939544Z","caller":"traceutil/trace.go:171","msg":"trace[703234027] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24187; }","duration":"210.510691ms","start":"2026-04-07T00:52:47.729011Z","end":"2026-04-07T00:52:47.939522Z","steps":["trace[703234027] 'range keys from in-memory index tree' (duration: 209.997618ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:48.162392Z","caller":"traceutil/trace.go:171","msg":"trace[419061204] transaction","detail":"{read_only:false; response_revision:24188; number_of_response:1; }","duration":"217.680706ms","start":"2026-04-07T00:52:47.944683Z","end":"2026-04-07T00:52:48.162364Z","steps":["trace[419061204] 'process raft request' (duration: 217.370627ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:48.162392Z","caller":"traceutil/trace.go:171","msg":"trace[454758880] transaction","detail":"{read_only:false; response_revision:24189; number_of_response:1; }","duration":"217.593424ms","start":"2026-04-07T00:52:47.944771Z","end":"2026-04-07T00:52:48.162365Z","steps":["trace[454758880] 'process raft request' (duration: 217.46936ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:48.162823Z","caller":"traceutil/trace.go:171","msg":"trace[189301592] transaction","detail":"{read_only:false; response_revision:24191; number_of_response:1; }","duration":"216.946896ms","start":"2026-04-07T00:52:47.945795Z","end":"2026-04-07T00:52:48.162742Z","steps":["trace[189301592] 'process raft request' (duration: 216.833483ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:48.162826Z","caller":"traceutil/trace.go:171","msg":"trace[7231501] transaction","detail":"{read_only:false; response_revision:24190; number_of_response:1; }","duration":"217.999234ms","start":"2026-04-07T00:52:47.944793Z","end":"2026-04-07T00:52:48.162792Z","steps":["trace[7231501] 'process raft request' (duration: 217.506761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:48.336731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.41723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:52:48.337215Z","caller":"traceutil/trace.go:171","msg":"trace[280994919] transaction","detail":"{read_only:false; response_revision:24193; number_of_response:1; }","duration":"120.392163ms","start":"2026-04-07T00:52:48.216801Z","end":"2026-04-07T00:52:48.337193Z","steps":["trace[280994919] 'process raft request' (duration: 120.051934ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:48.337364Z","caller":"traceutil/trace.go:171","msg":"trace[88578212] transaction","detail":"{read_only:false; response_revision:24192; number_of_response:1; }","duration":"381.089891ms","start":"2026-04-07T00:52:47.956196Z","end":"2026-04-07T00:52:48.337286Z","steps":["trace[88578212] 'process raft request' (duration: 270.013823ms)","trace[88578212] 'compare' (duration: 110.162053ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:48.337387Z","caller":"traceutil/trace.go:171","msg":"trace[721565949] linearizableReadLoop","detail":"{readStateIndex:24987; appliedIndex:24982; }","duration":"274.605778ms","start":"2026-04-07T00:52:48.062749Z","end":"2026-04-07T00:52:48.337355Z","steps":["trace[721565949] 'read index received' (duration: 99.299688ms)","trace[721565949] 'applied index is now lower than readState.Index' (duration: 175.30215ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:48.337554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.798083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:52:48.337574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:47.956179Z","time spent":"381.261737ms","remote":"127.0.0.1:36744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:48.337612Z","caller":"traceutil/trace.go:171","msg":"trace[424835202] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24194; }","duration":"274.898226ms","start":"2026-04-07T00:52:48.062696Z","end":"2026-04-07T00:52:48.337595Z","steps":["trace[424835202] 'agreement among raft nodes before linearized reading' (duration: 274.798353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:48.33762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.61022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:52:48.337682Z","caller":"traceutil/trace.go:171","msg":"trace[1581115166] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24194; }","duration":"189.717082ms","start":"2026-04-07T00:52:48.147939Z","end":"2026-04-07T00:52:48.337656Z","steps":["trace[1581115166] 'agreement among raft nodes before linearized reading' (duration: 189.589579ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:48.545264Z","caller":"traceutil/trace.go:171","msg":"trace[701662627] transaction","detail":"{read_only:false; response_revision:24195; number_of_response:1; }","duration":"106.600697ms","start":"2026-04-07T00:52:48.438633Z","end":"2026-04-07T00:52:48.545234Z","steps":["trace[701662627] 'process raft request' (duration: 75.787337ms)","trace[701662627] 'compare' (duration: 30.671097ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:49.033307Z","caller":"traceutil/trace.go:171","msg":"trace[662744346] transaction","detail":"{read_only:false; response_revision:24196; number_of_response:1; }","duration":"266.480708ms","start":"2026-04-07T00:52:48.766807Z","end":"2026-04-07T00:52:49.033288Z","steps":["trace[662744346] 'process raft request' (duration: 266.318384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:49.227171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.211318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:52:49.227334Z","caller":"traceutil/trace.go:171","msg":"trace[1268818252] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:24197; }","duration":"113.397623ms","start":"2026-04-07T00:52:49.113887Z","end":"2026-04-07T00:52:49.227284Z","steps":["trace[1268818252] 'count revisions from in-memory index tree' (duration: 113.070694ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:49.374516Z","caller":"traceutil/trace.go:171","msg":"trace[2048879125] transaction","detail":"{read_only:false; response_revision:24198; number_of_response:1; }","duration":"174.950771ms","start":"2026-04-07T00:52:49.199531Z","end":"2026-04-07T00:52:49.374482Z","steps":["trace[2048879125] 'process raft request' (duration: 174.660134ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:49.848961Z","caller":"traceutil/trace.go:171","msg":"trace[2028781525] transaction","detail":"{read_only:false; response_revision:24202; number_of_response:1; }","duration":"212.328961ms","start":"2026-04-07T00:52:49.636601Z","end":"2026-04-07T00:52:49.84893Z","steps":["trace[2028781525] 'process raft request' (duration: 212.156946ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:50.314471Z","caller":"traceutil/trace.go:171","msg":"trace[442455407] transaction","detail":"{read_only:false; response_revision:24204; number_of_response:1; }","duration":"105.435055ms","start":"2026-04-07T00:52:50.209012Z","end":"2026-04-07T00:52:50.314447Z","steps":["trace[442455407] 'process raft request' (duration: 96.269875ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:50.540345Z","caller":"traceutil/trace.go:171","msg":"trace[971014156] transaction","detail":"{read_only:false; response_revision:24205; number_of_response:1; }","duration":"331.159811ms","start":"2026-04-07T00:52:50.209155Z","end":"2026-04-07T00:52:50.540314Z","steps":["trace[971014156] 'process raft request' (duration: 310.718924ms)","trace[971014156] 'compare' (duration: 17.275471ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:50.540462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:50.209147Z","time spent":"331.250184ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:50.540663Z","caller":"traceutil/trace.go:171","msg":"trace[2141264877] transaction","detail":"{read_only:false; response_revision:24206; number_of_response:1; }","duration":"331.09744ms","start":"2026-04-07T00:52:50.209544Z","end":"2026-04-07T00:52:50.540642Z","steps":["trace[2141264877] 'process raft request' (duration: 330.622797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:50.540708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:50.209535Z","time spent":"331.145651ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:52:50.540769Z","caller":"traceutil/trace.go:171","msg":"trace[150245192] transaction","detail":"{read_only:false; response_revision:24207; number_of_response:1; }","duration":"331.158971ms","start":"2026-04-07T00:52:50.209602Z","end":"2026-04-07T00:52:50.540761Z","steps":["trace[150245192] 'process raft request' (duration: 330.914465ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:50.540747Z","caller":"traceutil/trace.go:171","msg":"trace[479902687] linearizableReadLoop","detail":"{readStateIndex:25002; appliedIndex:24999; }","duration":"224.545014ms","start":"2026-04-07T00:52:50.316127Z","end":"2026-04-07T00:52:50.540672Z","steps":["trace[479902687] 'read index received' (duration: 203.818979ms)","trace[479902687] 'applied index is now lower than readState.Index' (duration: 20.724225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:50.540802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:50.209597Z","time spent":"331.185372ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:50.540985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.831732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-07T00:52:50.541097Z","caller":"traceutil/trace.go:171","msg":"trace[2101779792] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24207; }","duration":"224.947655ms","start":"2026-04-07T00:52:50.3161Z","end":"2026-04-07T00:52:50.541048Z","steps":["trace[2101779792] 'agreement among raft nodes before linearized reading' (duration: 224.732339ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:50.577973Z","caller":"traceutil/trace.go:171","msg":"trace[1353544405] transaction","detail":"{read_only:false; response_revision:24208; number_of_response:1; }","duration":"234.849705ms","start":"2026-04-07T00:52:50.34309Z","end":"2026-04-07T00:52:50.57794Z","steps":["trace[1353544405] 'process raft request' (duration: 234.501026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:50.578173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.4348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:52:50.578278Z","caller":"traceutil/trace.go:171","msg":"trace[708640200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24208; }","duration":"155.591534ms","start":"2026-04-07T00:52:50.422661Z","end":"2026-04-07T00:52:50.578252Z","steps":["trace[708640200] 'agreement among raft nodes before linearized reading' (duration: 155.410029ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:50.948413Z","caller":"traceutil/trace.go:171","msg":"trace[2147034637] linearizableReadLoop","detail":"{readStateIndex:25004; appliedIndex:25003; }","duration":"370.34851ms","start":"2026-04-07T00:52:50.578038Z","end":"2026-04-07T00:52:50.948387Z","steps":["trace[2147034637] 'read index received' (duration: 279.015009ms)","trace[2147034637] 'applied index is now lower than readState.Index' (duration: 91.332531ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:50.948461Z","caller":"traceutil/trace.go:171","msg":"trace[1602661250] transaction","detail":"{read_only:false; response_revision:24209; number_of_response:1; }","duration":"397.314096ms","start":"2026-04-07T00:52:50.551109Z","end":"2026-04-07T00:52:50.948423Z","steps":["trace[1602661250] 'process raft request' (duration: 305.902403ms)","trace[1602661250] 'compare' (duration: 91.249199ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:50.948608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:50.55109Z","time spent":"397.436409ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:52:50.94862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.144767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:52:50.948668Z","caller":"traceutil/trace.go:171","msg":"trace[1458464061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24209; }","duration":"369.22808ms","start":"2026-04-07T00:52:50.579427Z","end":"2026-04-07T00:52:50.948655Z","steps":["trace[1458464061] 'agreement among raft nodes before linearized reading' (duration: 369.147987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:50.948699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:50.57941Z","time spent":"369.282322ms","remote":"127.0.0.1:36700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:52:50.948696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.721853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7ea5b5a8-eddf-42f2-adf2-4139b0d10495\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-07T00:52:50.94874Z","caller":"traceutil/trace.go:171","msg":"trace[822291162] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7ea5b5a8-eddf-42f2-adf2-4139b0d10495; range_end:; response_count:1; response_revision:24209; }","duration":"395.804924ms","start":"2026-04-07T00:52:50.552919Z","end":"2026-04-07T00:52:50.948724Z","steps":["trace[822291162] 'agreement among raft nodes before linearized reading' (duration: 395.616929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:50.948789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:50.552904Z","time spent":"395.876207ms","remote":"127.0.0.1:36870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1346,"request content":"key:\"/registry/persistentvolumes/pvc-7ea5b5a8-eddf-42f2-adf2-4139b0d10495\" "} {"level":"warn","ts":"2026-04-07T00:52:50.949074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.704924ms","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":"warn","ts":"2026-04-07T00:52:50.949348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.379947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-07T00:52:50.949387Z","caller":"traceutil/trace.go:171","msg":"trace[62846146] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24209; }","duration":"199.455099ms","start":"2026-04-07T00:52:50.749921Z","end":"2026-04-07T00:52:50.949376Z","steps":["trace[62846146] 'agreement among raft nodes before linearized reading' (duration: 199.308915ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:50.949411Z","caller":"traceutil/trace.go:171","msg":"trace[974315831] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24209; }","duration":"322.082764ms","start":"2026-04-07T00:52:50.627275Z","end":"2026-04-07T00:52:50.949358Z","steps":["trace[974315831] 'agreement among raft nodes before linearized reading' (duration: 321.57287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:50.949478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:52:50.627255Z","time spent":"322.208328ms","remote":"127.0.0.1:37458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":160,"response size":33,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:53:04.589629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.687095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-04-07T00:53:04.589709Z","caller":"traceutil/trace.go:171","msg":"trace[770615034] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd; range_end:; response_count:1; response_revision:24362; }","duration":"203.814738ms","start":"2026-04-07T00:53:04.385873Z","end":"2026-04-07T00:53:04.589688Z","steps":["trace[770615034] 'range keys from in-memory index tree' (duration: 203.492139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:04.59012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.85795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T00:53:04.590117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.298925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:04.590163Z","caller":"traceutil/trace.go:171","msg":"trace[1716203706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24362; }","duration":"181.929301ms","start":"2026-04-07T00:53:04.408223Z","end":"2026-04-07T00:53:04.590153Z","steps":["trace[1716203706] 'range keys from in-memory index tree' (duration: 181.763317ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:04.590179Z","caller":"traceutil/trace.go:171","msg":"trace[1698031552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24362; }","duration":"170.397118ms","start":"2026-04-07T00:53:04.419768Z","end":"2026-04-07T00:53:04.590166Z","steps":["trace[1698031552] 'range keys from in-memory index tree' (duration: 170.186081ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:04.781541Z","caller":"traceutil/trace.go:171","msg":"trace[987770472] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"155.83281ms","start":"2026-04-07T00:53:04.625663Z","end":"2026-04-07T00:53:04.781496Z","steps":["trace[987770472] 'process raft request' (duration: 143.264117ms)","trace[987770472] 'compare' (duration: 12.396168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:04.781638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.372363ms","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":"info","ts":"2026-04-07T00:53:04.781734Z","caller":"traceutil/trace.go:171","msg":"trace[337031534] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24364; }","duration":"175.491616ms","start":"2026-04-07T00:53:04.606216Z","end":"2026-04-07T00:53:04.781707Z","steps":["trace[337031534] 'agreement among raft nodes before linearized reading' (duration: 175.25981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:04.781766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.171388ms","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-04-07T00:53:04.781839Z","caller":"traceutil/trace.go:171","msg":"trace[1909457868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24364; }","duration":"171.262211ms","start":"2026-04-07T00:53:04.610558Z","end":"2026-04-07T00:53:04.78182Z","steps":["trace[1909457868] 'agreement among raft nodes before linearized reading' (duration: 170.992703ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:04.936987Z","caller":"traceutil/trace.go:171","msg":"trace[1429688000] transaction","detail":"{read_only:false; response_revision:24365; number_of_response:1; }","duration":"245.759412ms","start":"2026-04-07T00:53:04.6912Z","end":"2026-04-07T00:53:04.936959Z","steps":["trace[1429688000] 'process raft request' (duration: 245.603058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.352337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.455113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:53:05.352593Z","caller":"traceutil/trace.go:171","msg":"trace[1587078215] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"259.137657ms","start":"2026-04-07T00:53:05.093438Z","end":"2026-04-07T00:53:05.352576Z","steps":["trace[1587078215] 'process raft request' (duration: 259.096376ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.352596Z","caller":"traceutil/trace.go:171","msg":"trace[40027683] transaction","detail":"{read_only:false; response_revision:24366; number_of_response:1; }","duration":"566.170841ms","start":"2026-04-07T00:53:04.786402Z","end":"2026-04-07T00:53:05.352573Z","steps":["trace[40027683] 'process raft request' (duration: 279.40183ms)","trace[40027683] 'compare' (duration: 285.99626ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:05.352694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:04.786384Z","time spent":"566.262293ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:05.352899Z","caller":"traceutil/trace.go:171","msg":"trace[1396191836] transaction","detail":"{read_only:false; response_revision:24367; number_of_response:1; }","duration":"566.1429ms","start":"2026-04-07T00:53:04.786745Z","end":"2026-04-07T00:53:05.352887Z","steps":["trace[1396191836] 'process raft request' (duration: 565.682877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.352974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:04.786737Z","time spent":"566.198482ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:05.35306Z","caller":"traceutil/trace.go:171","msg":"trace[865153357] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"351.839555ms","start":"2026-04-07T00:53:05.00121Z","end":"2026-04-07T00:53:05.353049Z","steps":["trace[865153357] 'process raft request' (duration: 351.2837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.353115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:05.001188Z","time spent":"351.899947ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:05.353193Z","caller":"traceutil/trace.go:171","msg":"trace[319130220] linearizableReadLoop","detail":"{readStateIndex:25167; appliedIndex:25164; }","duration":"293.824933ms","start":"2026-04-07T00:53:05.059359Z","end":"2026-04-07T00:53:05.353184Z","steps":["trace[319130220] 'read index received' (duration: 6.450785ms)","trace[319130220] 'applied index is now lower than readState.Index' (duration: 287.373318ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:05.353556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.161622ms","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-04-07T00:53:05.353659Z","caller":"traceutil/trace.go:171","msg":"trace[1146954707] 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:24369; }","duration":"294.319006ms","start":"2026-04-07T00:53:05.059316Z","end":"2026-04-07T00:53:05.353635Z","steps":["trace[1146954707] 'agreement among raft nodes before linearized reading' (duration: 294.142421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.353645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.603227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:53:05.353708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.347839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd\" ","response":"range_response_count:1 size:3644"} {"level":"warn","ts":"2026-04-07T00:53:05.353741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.274757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-07T00:53:05.353758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.340684ms","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-04-07T00:53:05.353801Z","caller":"traceutil/trace.go:171","msg":"trace[1962857243] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24369; }","duration":"143.37583ms","start":"2026-04-07T00:53:05.210412Z","end":"2026-04-07T00:53:05.353788Z","steps":["trace[1962857243] 'agreement among raft nodes before linearized reading' (duration: 143.177765ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.35374Z","caller":"traceutil/trace.go:171","msg":"trace[1234687295] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:24369; }","duration":"176.742801ms","start":"2026-04-07T00:53:05.176975Z","end":"2026-04-07T00:53:05.353718Z","steps":["trace[1234687295] 'agreement among raft nodes before linearized reading' (duration: 176.560736ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.353801Z","caller":"traceutil/trace.go:171","msg":"trace[1811124179] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd; range_end:; response_count:1; response_revision:24369; }","duration":"264.503404ms","start":"2026-04-07T00:53:05.089276Z","end":"2026-04-07T00:53:05.35378Z","steps":["trace[1811124179] 'agreement among raft nodes before linearized reading' (duration: 264.238807ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.35382Z","caller":"traceutil/trace.go:171","msg":"trace[1873647261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24369; }","duration":"200.407606ms","start":"2026-04-07T00:53:05.153396Z","end":"2026-04-07T00:53:05.353804Z","steps":["trace[1873647261] 'agreement among raft nodes before linearized reading' (duration: 200.18631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.610246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.601782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:53:05.610392Z","caller":"traceutil/trace.go:171","msg":"trace[27432196] transaction","detail":"{read_only:false; response_revision:24370; number_of_response:1; }","duration":"252.529127ms","start":"2026-04-07T00:53:05.357839Z","end":"2026-04-07T00:53:05.610368Z","steps":["trace[27432196] 'process raft request' (duration: 110.71784ms)","trace[27432196] 'compare' (duration: 141.463327ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:05.695023Z","caller":"traceutil/trace.go:171","msg":"trace[328477256] transaction","detail":"{read_only:false; response_revision:24371; number_of_response:1; }","duration":"336.202369ms","start":"2026-04-07T00:53:05.358764Z","end":"2026-04-07T00:53:05.694967Z","steps":["trace[328477256] 'process raft request' (duration: 335.950122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.695178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:05.358756Z","time spent":"336.343113ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:05.695319Z","caller":"traceutil/trace.go:171","msg":"trace[1755384926] transaction","detail":"{read_only:false; response_revision:24372; number_of_response:1; }","duration":"334.245956ms","start":"2026-04-07T00:53:05.361046Z","end":"2026-04-07T00:53:05.695292Z","steps":["trace[1755384926] 'process raft request' (duration: 333.802674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.695418Z","caller":"traceutil/trace.go:171","msg":"trace[1118905075] transaction","detail":"{read_only:false; response_revision:24373; number_of_response:1; }","duration":"262.863869ms","start":"2026-04-07T00:53:05.43253Z","end":"2026-04-07T00:53:05.695394Z","steps":["trace[1118905075] 'process raft request' (duration: 262.703264ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.695556Z","caller":"traceutil/trace.go:171","msg":"trace[1425905701] transaction","detail":"{read_only:false; response_revision:24374; number_of_response:1; }","duration":"256.325991ms","start":"2026-04-07T00:53:05.439222Z","end":"2026-04-07T00:53:05.695548Z","steps":["trace[1425905701] 'process raft request' (duration: 256.125235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.695452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:05.361038Z","time spent":"334.345498ms","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:05.69557Z","caller":"traceutil/trace.go:171","msg":"trace[270903] transaction","detail":"{read_only:false; response_revision:24375; number_of_response:1; }","duration":"253.834652ms","start":"2026-04-07T00:53:05.441724Z","end":"2026-04-07T00:53:05.695559Z","steps":["trace[270903] 'process raft request' (duration: 253.75913ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.695602Z","caller":"traceutil/trace.go:171","msg":"trace[1137660229] linearizableReadLoop","detail":"{readStateIndex:25171; appliedIndex:25168; }","duration":"332.502218ms","start":"2026-04-07T00:53:05.363087Z","end":"2026-04-07T00:53:05.69559Z","steps":["trace[1137660229] 'read index received' (duration: 105.707773ms)","trace[1137660229] 'applied index is now lower than readState.Index' (duration: 226.793415ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:05.695667Z","caller":"traceutil/trace.go:171","msg":"trace[1522851451] transaction","detail":"{read_only:false; response_revision:24376; number_of_response:1; }","duration":"244.450817ms","start":"2026-04-07T00:53:05.451207Z","end":"2026-04-07T00:53:05.695658Z","steps":["trace[1522851451] 'process raft request' (duration: 244.323763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.695737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.631101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:53:05.695776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.398828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-04-07T00:53:05.695779Z","caller":"traceutil/trace.go:171","msg":"trace[74195429] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24376; }","duration":"332.792276ms","start":"2026-04-07T00:53:05.362974Z","end":"2026-04-07T00:53:05.695766Z","steps":["trace[74195429] 'agreement among raft nodes before linearized reading' (duration: 332.687413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.695787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.569068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-04-07T00:53:05.695814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:05.362954Z","time spent":"332.850488ms","remote":"127.0.0.1:36912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-04-07T00:53:05.695825Z","caller":"traceutil/trace.go:171","msg":"trace[1081988222] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24376; }","duration":"329.621059ms","start":"2026-04-07T00:53:05.366192Z","end":"2026-04-07T00:53:05.695813Z","steps":["trace[1081988222] 'agreement among raft nodes before linearized reading' (duration: 329.494016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.695857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.041431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:05.695877Z","caller":"traceutil/trace.go:171","msg":"trace[1835886502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24376; }","duration":"275.087933ms","start":"2026-04-07T00:53:05.420783Z","end":"2026-04-07T00:53:05.695871Z","steps":["trace[1835886502] 'agreement among raft nodes before linearized reading' (duration: 275.044361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.695961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:05.366185Z","time spent":"329.664711ms","remote":"127.0.0.1:51864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3260,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-04-07T00:53:05.695799Z","caller":"traceutil/trace.go:171","msg":"trace[1342079404] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd; range_end:; response_count:1; response_revision:24376; }","duration":"100.425838ms","start":"2026-04-07T00:53:05.595366Z","end":"2026-04-07T00:53:05.695792Z","steps":["trace[1342079404] 'agreement among raft nodes before linearized reading' (duration: 100.374867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.906017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.570992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18a3eced9c470c0b\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-04-07T00:53:05.906132Z","caller":"traceutil/trace.go:171","msg":"trace[1421796895] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18a3eced9c470c0b; range_end:; response_count:1; response_revision:24376; }","duration":"192.712756ms","start":"2026-04-07T00:53:05.713387Z","end":"2026-04-07T00:53:05.906099Z","steps":["trace[1421796895] 'range keys from in-memory index tree' (duration: 192.342825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:05.906045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.334553ms","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":"warn","ts":"2026-04-07T00:53:05.906206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.073953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1444"} {"level":"info","ts":"2026-04-07T00:53:05.906279Z","caller":"traceutil/trace.go:171","msg":"trace[1261959450] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24376; }","duration":"190.240699ms","start":"2026-04-07T00:53:05.716019Z","end":"2026-04-07T00:53:05.906259Z","steps":["trace[1261959450] 'range keys from in-memory index tree' (duration: 189.890709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:05.906271Z","caller":"traceutil/trace.go:171","msg":"trace[1764913162] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24376; }","duration":"193.5894ms","start":"2026-04-07T00:53:05.712649Z","end":"2026-04-07T00:53:05.906239Z","steps":["trace[1764913162] 'range keys from in-memory index tree' (duration: 193.24703ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:06.080283Z","caller":"traceutil/trace.go:171","msg":"trace[590363579] linearizableReadLoop","detail":"{readStateIndex:25176; appliedIndex:25175; }","duration":"169.351948ms","start":"2026-04-07T00:53:05.910908Z","end":"2026-04-07T00:53:06.08026Z","steps":["trace[590363579] 'read index received' (duration: 169.138762ms)","trace[590363579] 'applied index is now lower than readState.Index' (duration: 212.226µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:06.080348Z","caller":"traceutil/trace.go:171","msg":"trace[577394564] transaction","detail":"{read_only:false; response_revision:24377; number_of_response:1; }","duration":"172.155545ms","start":"2026-04-07T00:53:05.908164Z","end":"2026-04-07T00:53:06.08032Z","steps":["trace[577394564] 'process raft request' (duration: 171.915318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:06.08051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.588395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"warn","ts":"2026-04-07T00:53:06.080696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.174344ms","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-04-07T00:53:06.080738Z","caller":"traceutil/trace.go:171","msg":"trace[724794105] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24377; }","duration":"169.824242ms","start":"2026-04-07T00:53:05.910899Z","end":"2026-04-07T00:53:06.080724Z","steps":["trace[724794105] 'agreement among raft nodes before linearized reading' (duration: 169.492043ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:06.08076Z","caller":"traceutil/trace.go:171","msg":"trace[440812444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24377; }","duration":"158.261966ms","start":"2026-04-07T00:53:05.922482Z","end":"2026-04-07T00:53:06.080744Z","steps":["trace[440812444] 'agreement among raft nodes before linearized reading' (duration: 158.124143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:06.080718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.55426ms","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-04-07T00:53:06.080831Z","caller":"traceutil/trace.go:171","msg":"trace[118014238] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24377; }","duration":"167.725674ms","start":"2026-04-07T00:53:05.913085Z","end":"2026-04-07T00:53:06.080811Z","steps":["trace[118014238] 'agreement among raft nodes before linearized reading' (duration: 167.511628ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:06.186954Z","caller":"traceutil/trace.go:171","msg":"trace[1974622971] transaction","detail":"{read_only:false; response_revision:24378; number_of_response:1; }","duration":"102.301969ms","start":"2026-04-07T00:53:06.084602Z","end":"2026-04-07T00:53:06.186904Z","steps":["trace[1974622971] 'process raft request' (duration: 86.94662ms)","trace[1974622971] 'compare' (duration: 15.154533ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:06.186969Z","caller":"traceutil/trace.go:171","msg":"trace[981430855] linearizableReadLoop","detail":"{readStateIndex:25177; appliedIndex:25176; }","duration":"101.480997ms","start":"2026-04-07T00:53:06.085463Z","end":"2026-04-07T00:53:06.186944Z","steps":["trace[981430855] 'read index received' (duration: 86.075967ms)","trace[981430855] 'applied index is now lower than readState.Index' (duration: 15.40403ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:06.187255Z","caller":"traceutil/trace.go:171","msg":"trace[1897055789] transaction","detail":"{read_only:false; response_revision:24379; number_of_response:1; }","duration":"100.546161ms","start":"2026-04-07T00:53:06.086681Z","end":"2026-04-07T00:53:06.187228Z","steps":["trace[1897055789] 'process raft request' (duration: 100.198472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:06.187505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.023981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-04-07T00:53:06.187574Z","caller":"traceutil/trace.go:171","msg":"trace[990890789] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24379; }","duration":"102.134594ms","start":"2026-04-07T00:53:06.085421Z","end":"2026-04-07T00:53:06.187555Z","steps":["trace[990890789] 'agreement among raft nodes before linearized reading' (duration: 101.59219ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:06.360263Z","caller":"traceutil/trace.go:171","msg":"trace[1280170488] linearizableReadLoop","detail":"{readStateIndex:25179; appliedIndex:25178; }","duration":"138.530218ms","start":"2026-04-07T00:53:06.221704Z","end":"2026-04-07T00:53:06.360234Z","steps":["trace[1280170488] 'read index received' (duration: 105.552408ms)","trace[1280170488] 'applied index is now lower than readState.Index' (duration: 32.97335ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:06.360368Z","caller":"traceutil/trace.go:171","msg":"trace[2099762857] transaction","detail":"{read_only:false; response_revision:24380; number_of_response:1; }","duration":"159.800539ms","start":"2026-04-07T00:53:06.200538Z","end":"2026-04-07T00:53:06.360338Z","steps":["trace[2099762857] 'process raft request' (duration: 126.732707ms)","trace[2099762857] 'compare' (duration: 32.664671ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:06.360509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.805786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-07T00:53:06.360536Z","caller":"traceutil/trace.go:171","msg":"trace[481326373] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24381; }","duration":"138.869198ms","start":"2026-04-07T00:53:06.221659Z","end":"2026-04-07T00:53:06.360528Z","steps":["trace[481326373] 'agreement among raft nodes before linearized reading' (duration: 138.719214ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:06.874284Z","caller":"traceutil/trace.go:171","msg":"trace[1949433916] linearizableReadLoop","detail":"{readStateIndex:25181; appliedIndex:25180; }","duration":"173.289487ms","start":"2026-04-07T00:53:06.700976Z","end":"2026-04-07T00:53:06.874266Z","steps":["trace[1949433916] 'read index received' (duration: 173.232235ms)","trace[1949433916] 'applied index is now lower than readState.Index' (duration: 56.462µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:06.874415Z","caller":"traceutil/trace.go:171","msg":"trace[425009705] transaction","detail":"{read_only:false; response_revision:24382; number_of_response:1; }","duration":"179.094985ms","start":"2026-04-07T00:53:06.695283Z","end":"2026-04-07T00:53:06.874378Z","steps":["trace[425009705] 'process raft request' (duration: 178.859108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:06.874461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.468541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-04-07T00:53:06.874486Z","caller":"traceutil/trace.go:171","msg":"trace[1721866630] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-55d2459f-acbc-4287-8412-f81bf00629fd; range_end:; response_count:1; response_revision:24382; }","duration":"173.528362ms","start":"2026-04-07T00:53:06.70095Z","end":"2026-04-07T00:53:06.874479Z","steps":["trace[1721866630] 'agreement among raft nodes before linearized reading' (duration: 173.388699ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:07.156576Z","caller":"traceutil/trace.go:171","msg":"trace[1106718564] linearizableReadLoop","detail":"{readStateIndex:25182; appliedIndex:25181; }","duration":"215.660991ms","start":"2026-04-07T00:53:06.940892Z","end":"2026-04-07T00:53:07.156553Z","steps":["trace[1106718564] 'read index received' (duration: 145.296362ms)","trace[1106718564] 'applied index is now lower than readState.Index' (duration: 70.363499ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:07.156674Z","caller":"traceutil/trace.go:171","msg":"trace[422378051] transaction","detail":"{read_only:false; response_revision:24383; number_of_response:1; }","duration":"370.049723ms","start":"2026-04-07T00:53:06.786582Z","end":"2026-04-07T00:53:07.156632Z","steps":["trace[422378051] 'process raft request' (duration: 299.504288ms)","trace[422378051] 'compare' (duration: 70.336769ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:07.156789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.879378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:53:07.156823Z","caller":"traceutil/trace.go:171","msg":"trace[1895084363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24383; }","duration":"215.95725ms","start":"2026-04-07T00:53:06.940855Z","end":"2026-04-07T00:53:07.156813Z","steps":["trace[1895084363] 'agreement among raft nodes before linearized reading' (duration: 215.825386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:07.156843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:06.786558Z","time spent":"370.191856ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:09.49325Z","caller":"traceutil/trace.go:171","msg":"trace[310149301] transaction","detail":"{read_only:false; response_revision:24403; number_of_response:1; }","duration":"185.671523ms","start":"2026-04-07T00:53:09.307558Z","end":"2026-04-07T00:53:09.49323Z","steps":["trace[310149301] 'process raft request' (duration: 185.407936ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:09.715356Z","caller":"traceutil/trace.go:171","msg":"trace[193846687] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"350.710245ms","start":"2026-04-07T00:53:09.364623Z","end":"2026-04-07T00:53:09.715333Z","steps":["trace[193846687] 'process raft request' (duration: 311.036223ms)","trace[193846687] 'compare' (duration: 39.529238ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:09.715475Z","caller":"traceutil/trace.go:171","msg":"trace[1449203055] linearizableReadLoop","detail":"{readStateIndex:25205; appliedIndex:25202; }","duration":"348.067593ms","start":"2026-04-07T00:53:09.367374Z","end":"2026-04-07T00:53:09.715441Z","steps":["trace[1449203055] 'read index received' (duration: 125.519133ms)","trace[1449203055] 'applied index is now lower than readState.Index' (duration: 222.54684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:09.715488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.364601Z","time spent":"350.820508ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:09.715659Z","caller":"traceutil/trace.go:171","msg":"trace[979602212] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"348.931576ms","start":"2026-04-07T00:53:09.366706Z","end":"2026-04-07T00:53:09.715637Z","steps":["trace[979602212] 'process raft request' (duration: 348.593247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:09.715769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.380211ms","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-04-07T00:53:09.715789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.366684Z","time spent":"349.033979ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:09.715819Z","caller":"traceutil/trace.go:171","msg":"trace[1148059122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24405; }","duration":"348.462364ms","start":"2026-04-07T00:53:09.367346Z","end":"2026-04-07T00:53:09.715808Z","steps":["trace[1148059122] 'agreement among raft nodes before linearized reading' (duration: 348.283389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:09.715843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.367337Z","time spent":"348.500314ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:53:09.778963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.507671ms","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":"info","ts":"2026-04-07T00:53:09.779097Z","caller":"traceutil/trace.go:171","msg":"trace[1818220718] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24410; }","duration":"411.667196ms","start":"2026-04-07T00:53:09.367401Z","end":"2026-04-07T00:53:09.779068Z","steps":["trace[1818220718] 'agreement among raft nodes before linearized reading' (duration: 411.409019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:09.779145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.367384Z","time spent":"411.747818ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:53:09.779818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.49667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:09.779848Z","caller":"traceutil/trace.go:171","msg":"trace[2101410287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24410; }","duration":"360.526141ms","start":"2026-04-07T00:53:09.419314Z","end":"2026-04-07T00:53:09.77984Z","steps":["trace[2101410287] 'agreement among raft nodes before linearized reading' (duration: 360.463739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:09.779866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.419254Z","time spent":"360.607943ms","remote":"127.0.0.1:36712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:53:09.780057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.489255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:53:09.780125Z","caller":"traceutil/trace.go:171","msg":"trace[1040039504] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24410; }","duration":"241.598398ms","start":"2026-04-07T00:53:09.538509Z","end":"2026-04-07T00:53:09.780107Z","steps":["trace[1040039504] 'agreement among raft nodes before linearized reading' (duration: 241.464484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:09.780225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.734955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:268845"} {"level":"info","ts":"2026-04-07T00:53:09.780257Z","caller":"traceutil/trace.go:171","msg":"trace[386348449] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:24410; }","duration":"335.806697ms","start":"2026-04-07T00:53:09.444442Z","end":"2026-04-07T00:53:09.780249Z","steps":["trace[386348449] 'agreement among raft nodes before linearized reading' (duration: 335.498518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:09.780276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:09.444423Z","time spent":"335.847588ms","remote":"127.0.0.1:37156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":31,"response size":268869,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-04-07T00:53:13.031808Z","caller":"traceutil/trace.go:171","msg":"trace[633837768] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"129.477931ms","start":"2026-04-07T00:53:12.902303Z","end":"2026-04-07T00:53:13.031781Z","steps":["trace[633837768] 'process raft request' (duration: 129.294906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:42.376388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.680586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-07T00:53:42.376608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.365815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-07T00:53:42.376659Z","caller":"traceutil/trace.go:171","msg":"trace[1667383628] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24689; }","duration":"189.011485ms","start":"2026-04-07T00:53:42.187627Z","end":"2026-04-07T00:53:42.376639Z","steps":["trace[1667383628] 'count revisions from in-memory index tree' (duration: 188.602843ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:42.376669Z","caller":"traceutil/trace.go:171","msg":"trace[1013511934] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24689; }","duration":"229.474448ms","start":"2026-04-07T00:53:42.14718Z","end":"2026-04-07T00:53:42.376654Z","steps":["trace[1013511934] 'range keys from in-memory index tree' (duration: 229.024646ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:42.742007Z","caller":"traceutil/trace.go:171","msg":"trace[1877929076] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"360.026779ms","start":"2026-04-07T00:53:42.381953Z","end":"2026-04-07T00:53:42.74198Z","steps":["trace[1877929076] 'process raft request' (duration: 359.879895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:42.742169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:42.381922Z","time spent":"360.167513ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:42.742179Z","caller":"traceutil/trace.go:171","msg":"trace[1296723226] linearizableReadLoop","detail":"{readStateIndex:25496; appliedIndex:25495; }","duration":"359.271448ms","start":"2026-04-07T00:53:42.38287Z","end":"2026-04-07T00:53:42.742141Z","steps":["trace[1296723226] 'read index received' (duration: 358.835606ms)","trace[1296723226] 'applied index is now lower than readState.Index' (duration: 433.172µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:42.742375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.248817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:42.742412Z","caller":"traceutil/trace.go:171","msg":"trace[1868069721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24690; }","duration":"319.325369ms","start":"2026-04-07T00:53:42.423072Z","end":"2026-04-07T00:53:42.742397Z","steps":["trace[1868069721] 'agreement among raft nodes before linearized reading' (duration: 319.242197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:42.742447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:42.423026Z","time spent":"319.412181ms","remote":"127.0.0.1:36712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:53:42.74244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.566136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-04-07T00:53:42.742494Z","caller":"traceutil/trace.go:171","msg":"trace[775871590] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24690; }","duration":"359.646898ms","start":"2026-04-07T00:53:42.382832Z","end":"2026-04-07T00:53:42.742479Z","steps":["trace[775871590] 'agreement among raft nodes before linearized reading' (duration: 359.454033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:42.742525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:42.382818Z","time spent":"359.700101ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18595,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-07T00:53:42.742548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.786803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:42.742592Z","caller":"traceutil/trace.go:171","msg":"trace[919735753] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24690; }","duration":"146.870895ms","start":"2026-04-07T00:53:42.595708Z","end":"2026-04-07T00:53:42.742579Z","steps":["trace[919735753] 'agreement among raft nodes before linearized reading' (duration: 146.796623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:42.742686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.633903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:42.742718Z","caller":"traceutil/trace.go:171","msg":"trace[2078347705] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:24690; }","duration":"317.670244ms","start":"2026-04-07T00:53:42.425038Z","end":"2026-04-07T00:53:42.742709Z","steps":["trace[2078347705] 'agreement among raft nodes before linearized reading' (duration: 317.615902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:42.742745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:42.425022Z","time spent":"317.714875ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:53:42.913384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.204425ms","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-04-07T00:53:42.913494Z","caller":"traceutil/trace.go:171","msg":"trace[596294512] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24690; }","duration":"110.411012ms","start":"2026-04-07T00:53:42.803057Z","end":"2026-04-07T00:53:42.913468Z","steps":["trace[596294512] 'range keys from in-memory index tree' (duration: 110.032591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:42.913698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.2689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:53:42.913793Z","caller":"traceutil/trace.go:171","msg":"trace[178174732] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24690; }","duration":"131.447055ms","start":"2026-04-07T00:53:42.782324Z","end":"2026-04-07T00:53:42.913771Z","steps":["trace[178174732] 'count revisions from in-memory index tree' (duration: 131.193088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:43.53092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.560793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:53:43.531081Z","caller":"traceutil/trace.go:171","msg":"trace[460852055] linearizableReadLoop","detail":"{readStateIndex:25499; appliedIndex:25498; }","duration":"220.601746ms","start":"2026-04-07T00:53:43.310466Z","end":"2026-04-07T00:53:43.531068Z","steps":["trace[460852055] 'read index received' (duration: 31.828058ms)","trace[460852055] 'applied index is now lower than readState.Index' (duration: 188.772798ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:43.531134Z","caller":"traceutil/trace.go:171","msg":"trace[414036765] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"254.806819ms","start":"2026-04-07T00:53:43.276318Z","end":"2026-04-07T00:53:43.531125Z","steps":["trace[414036765] 'process raft request' (duration: 65.970069ms)","trace[414036765] 'compare' (duration: 188.404088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:43.53159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.124721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-07T00:53:43.531634Z","caller":"traceutil/trace.go:171","msg":"trace[259429887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24693; }","duration":"221.192532ms","start":"2026-04-07T00:53:43.310431Z","end":"2026-04-07T00:53:43.531624Z","steps":["trace[259429887] 'agreement among raft nodes before linearized reading' (duration: 220.674828ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:43.532613Z","caller":"traceutil/trace.go:171","msg":"trace[70637765] transaction","detail":"{read_only:false; response_revision:24693; number_of_response:1; }","duration":"109.919437ms","start":"2026-04-07T00:53:43.422659Z","end":"2026-04-07T00:53:43.532579Z","steps":["trace[70637765] 'process raft request' (duration: 108.373495ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:44.221253Z","caller":"traceutil/trace.go:171","msg":"trace[880627143] transaction","detail":"{read_only:false; response_revision:24695; number_of_response:1; }","duration":"389.541544ms","start":"2026-04-07T00:53:43.831247Z","end":"2026-04-07T00:53:44.220789Z","steps":["trace[880627143] 'process raft request' (duration: 389.341248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.221421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.831229Z","time spent":"390.10797ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:44.419836Z","caller":"traceutil/trace.go:171","msg":"trace[1001047188] transaction","detail":"{read_only:false; response_revision:24696; number_of_response:1; }","duration":"588.046557ms","start":"2026-04-07T00:53:43.831766Z","end":"2026-04-07T00:53:44.419813Z","steps":["trace[1001047188] 'process raft request' (duration: 536.529182ms)","trace[1001047188] 'compare' (duration: 50.987881ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:44.419954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.831761Z","time spent":"588.13521ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:44.420286Z","caller":"traceutil/trace.go:171","msg":"trace[953622071] transaction","detail":"{read_only:false; response_revision:24697; number_of_response:1; }","duration":"530.513799ms","start":"2026-04-07T00:53:43.889754Z","end":"2026-04-07T00:53:44.420268Z","steps":["trace[953622071] 'process raft request' (duration: 529.967604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.420387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.889735Z","time spent":"530.59823ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:44.420435Z","caller":"traceutil/trace.go:171","msg":"trace[256573679] transaction","detail":"{read_only:false; response_revision:24698; number_of_response:1; }","duration":"529.533471ms","start":"2026-04-07T00:53:43.890858Z","end":"2026-04-07T00:53:44.420391Z","steps":["trace[256573679] 'process raft request' (duration: 529.310635ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:44.420384Z","caller":"traceutil/trace.go:171","msg":"trace[579729520] transaction","detail":"{read_only:false; response_revision:24699; number_of_response:1; }","duration":"527.076895ms","start":"2026-04-07T00:53:43.893298Z","end":"2026-04-07T00:53:44.420375Z","steps":["trace[579729520] 'process raft request' (duration: 526.991992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.420669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.89085Z","time spent":"529.705547ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:44.420718Z","caller":"traceutil/trace.go:171","msg":"trace[429440881] transaction","detail":"{read_only:false; response_revision:24700; number_of_response:1; }","duration":"525.294506ms","start":"2026-04-07T00:53:43.895416Z","end":"2026-04-07T00:53:44.420711Z","steps":["trace[429440881] 'process raft request' (duration: 524.938296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.420753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.895407Z","time spent":"525.329657ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:44.42075Z","caller":"traceutil/trace.go:171","msg":"trace[762403871] linearizableReadLoop","detail":"{readStateIndex:25508; appliedIndex:25501; }","duration":"477.533783ms","start":"2026-04-07T00:53:43.943187Z","end":"2026-04-07T00:53:44.420721Z","steps":["trace[762403871] 'read index received' (duration: 277.340313ms)","trace[762403871] 'applied index is now lower than readState.Index' (duration: 200.19233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:44.420898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.710568ms","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-04-07T00:53:44.420925Z","caller":"traceutil/trace.go:171","msg":"trace[1013262395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24701; }","duration":"477.764439ms","start":"2026-04-07T00:53:43.943151Z","end":"2026-04-07T00:53:44.420916Z","steps":["trace[1013262395] 'agreement among raft nodes before linearized reading' (duration: 477.681497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.420793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.89329Z","time spent":"527.262239ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:44.420946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.943135Z","time spent":"477.804461ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:53:44.421084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.656695ms","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-04-07T00:53:44.420792Z","caller":"traceutil/trace.go:171","msg":"trace[1204717091] transaction","detail":"{read_only:false; response_revision:24701; number_of_response:1; }","duration":"500.819119ms","start":"2026-04-07T00:53:43.919937Z","end":"2026-04-07T00:53:44.420756Z","steps":["trace[1204717091] 'process raft request' (duration: 500.676825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.421199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.919918Z","time spent":"501.204949ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:44.421249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.093476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:44.421274Z","caller":"traceutil/trace.go:171","msg":"trace[2044476424] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:24701; }","duration":"343.167249ms","start":"2026-04-07T00:53:44.078097Z","end":"2026-04-07T00:53:44.421265Z","steps":["trace[2044476424] 'agreement among raft nodes before linearized reading' (duration: 343.128348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.421297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:44.078026Z","time spent":"343.264202ms","remote":"127.0.0.1:37724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:53:44.421102Z","caller":"traceutil/trace.go:171","msg":"trace[1878249941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24701; }","duration":"474.697946ms","start":"2026-04-07T00:53:43.946398Z","end":"2026-04-07T00:53:44.421096Z","steps":["trace[1878249941] 'agreement among raft nodes before linearized reading' (duration: 474.647575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.421333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.946384Z","time spent":"474.938282ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:53:44.421393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.923589ms","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":"info","ts":"2026-04-07T00:53:44.421422Z","caller":"traceutil/trace.go:171","msg":"trace[882178889] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24701; }","duration":"442.983981ms","start":"2026-04-07T00:53:43.978429Z","end":"2026-04-07T00:53:44.421413Z","steps":["trace[882178889] 'agreement among raft nodes before linearized reading' (duration: 442.892068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.421438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.978393Z","time spent":"443.041403ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:53:44.421455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.915637ms","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-04-07T00:53:44.421499Z","caller":"traceutil/trace.go:171","msg":"trace[1110819307] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24701; }","duration":"422.010989ms","start":"2026-04-07T00:53:43.999476Z","end":"2026-04-07T00:53:44.421487Z","steps":["trace[1110819307] 'agreement among raft nodes before linearized reading' (duration: 421.930667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:44.42153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:43.999462Z","time spent":"422.058401ms","remote":"127.0.0.1:51890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:53:44.616582Z","caller":"traceutil/trace.go:171","msg":"trace[1789563468] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"189.260722ms","start":"2026-04-07T00:53:44.42729Z","end":"2026-04-07T00:53:44.61655Z","steps":["trace[1789563468] 'process raft request' (duration: 181.786248ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:44.616629Z","caller":"traceutil/trace.go:171","msg":"trace[1704828724] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"187.471713ms","start":"2026-04-07T00:53:44.429096Z","end":"2026-04-07T00:53:44.616567Z","steps":["trace[1704828724] 'process raft request' (duration: 187.343749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:45.166505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.32164ms","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-04-07T00:53:45.166658Z","caller":"traceutil/trace.go:171","msg":"trace[71334401] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24705; }","duration":"227.511785ms","start":"2026-04-07T00:53:44.939124Z","end":"2026-04-07T00:53:45.166636Z","steps":["trace[71334401] 'count revisions from in-memory index tree' (duration: 227.213797ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:45.314602Z","caller":"traceutil/trace.go:171","msg":"trace[1861040188] transaction","detail":"{read_only:false; response_revision:24706; number_of_response:1; }","duration":"108.476708ms","start":"2026-04-07T00:53:45.206105Z","end":"2026-04-07T00:53:45.314582Z","steps":["trace[1861040188] 'process raft request' (duration: 108.362605ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:45.699505Z","caller":"traceutil/trace.go:171","msg":"trace[705316159] linearizableReadLoop","detail":"{readStateIndex:25514; appliedIndex:25513; }","duration":"134.498468ms","start":"2026-04-07T00:53:45.564965Z","end":"2026-04-07T00:53:45.699463Z","steps":["trace[705316159] 'read index received' (duration: 134.241991ms)","trace[705316159] 'applied index is now lower than readState.Index' (duration: 255.516µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:45.699665Z","caller":"traceutil/trace.go:171","msg":"trace[378147761] transaction","detail":"{read_only:false; response_revision:24707; number_of_response:1; }","duration":"158.092471ms","start":"2026-04-07T00:53:45.541549Z","end":"2026-04-07T00:53:45.699642Z","steps":["trace[378147761] 'process raft request' (duration: 157.698951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:45.699829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.847048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:53:45.700211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.407813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:45.700296Z","caller":"traceutil/trace.go:171","msg":"trace[132856918] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24707; }","duration":"135.366722ms","start":"2026-04-07T00:53:45.564906Z","end":"2026-04-07T00:53:45.700272Z","steps":["trace[132856918] 'agreement among raft nodes before linearized reading' (duration: 134.815737ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:45.700299Z","caller":"traceutil/trace.go:171","msg":"trace[1610266384] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:24707; }","duration":"102.538707ms","start":"2026-04-07T00:53:45.597739Z","end":"2026-04-07T00:53:45.700278Z","steps":["trace[1610266384] 'agreement among raft nodes before linearized reading' (duration: 102.402413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:46.54806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.627706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:46.54815Z","caller":"traceutil/trace.go:171","msg":"trace[907667505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24710; }","duration":"125.810241ms","start":"2026-04-07T00:53:46.42232Z","end":"2026-04-07T00:53:46.54813Z","steps":["trace[907667505] 'range keys from in-memory index tree' (duration: 125.476712ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:46.675134Z","caller":"traceutil/trace.go:171","msg":"trace[1873891456] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"249.437273ms","start":"2026-04-07T00:53:46.425663Z","end":"2026-04-07T00:53:46.6751Z","steps":["trace[1873891456] 'process raft request' (duration: 249.225237ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:46.83822Z","caller":"traceutil/trace.go:171","msg":"trace[744232959] transaction","detail":"{read_only:false; response_revision:24712; number_of_response:1; }","duration":"412.252203ms","start":"2026-04-07T00:53:46.425931Z","end":"2026-04-07T00:53:46.838183Z","steps":["trace[744232959] 'process raft request' (duration: 380.347813ms)","trace[744232959] 'compare' (duration: 31.567791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:46.83841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:46.425901Z","time spent":"412.425088ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:46.838544Z","caller":"traceutil/trace.go:171","msg":"trace[767904969] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"411.935575ms","start":"2026-04-07T00:53:46.426581Z","end":"2026-04-07T00:53:46.838517Z","steps":["trace[767904969] 'process raft request' (duration: 411.528413ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:46.838568Z","caller":"traceutil/trace.go:171","msg":"trace[1762784579] transaction","detail":"{read_only:false; response_revision:24714; number_of_response:1; }","duration":"410.865635ms","start":"2026-04-07T00:53:46.427677Z","end":"2026-04-07T00:53:46.838543Z","steps":["trace[1762784579] 'process raft request' (duration: 410.719461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:46.838667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:46.426556Z","time spent":"412.043088ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:46.838694Z","caller":"traceutil/trace.go:171","msg":"trace[1800426342] transaction","detail":"{read_only:false; response_revision:24715; number_of_response:1; }","duration":"409.102447ms","start":"2026-04-07T00:53:46.429573Z","end":"2026-04-07T00:53:46.838676Z","steps":["trace[1800426342] 'process raft request' (duration: 408.927352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:46.838709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:46.427653Z","time spent":"410.979839ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:46.838805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:46.429563Z","time spent":"409.18124ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:46.838825Z","caller":"traceutil/trace.go:171","msg":"trace[503066631] linearizableReadLoop","detail":"{readStateIndex:25522; appliedIndex:25518; }","duration":"392.038291ms","start":"2026-04-07T00:53:46.44677Z","end":"2026-04-07T00:53:46.838808Z","steps":["trace[503066631] 'read index received' (duration: 228.707937ms)","trace[503066631] 'applied index is now lower than readState.Index' (duration: 163.328774ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:46.838907Z","caller":"traceutil/trace.go:171","msg":"trace[621830835] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"367.158383ms","start":"2026-04-07T00:53:46.471722Z","end":"2026-04-07T00:53:46.83888Z","steps":["trace[621830835] 'process raft request' (duration: 366.884886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:46.838961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.204186ms","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-04-07T00:53:46.838957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.795263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:46.839019Z","caller":"traceutil/trace.go:171","msg":"trace[2053180174] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24716; }","duration":"392.259158ms","start":"2026-04-07T00:53:46.446718Z","end":"2026-04-07T00:53:46.838977Z","steps":["trace[2053180174] 'agreement among raft nodes before linearized reading' (duration: 392.175346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:46.839057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:46.471704Z","time spent":"367.257556ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:46.839082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:46.446696Z","time spent":"392.371781ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:53:46.839165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.444777ms","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-04-07T00:53:46.839027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.184404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:46.839195Z","caller":"traceutil/trace.go:171","msg":"trace[1912198364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24716; }","duration":"218.500859ms","start":"2026-04-07T00:53:46.620685Z","end":"2026-04-07T00:53:46.839186Z","steps":["trace[1912198364] 'agreement among raft nodes before linearized reading' (duration: 218.393786ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:46.839211Z","caller":"traceutil/trace.go:171","msg":"trace[1553103984] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:24716; }","duration":"103.40319ms","start":"2026-04-07T00:53:46.735798Z","end":"2026-04-07T00:53:46.839201Z","steps":["trace[1553103984] 'agreement among raft nodes before linearized reading' (duration: 103.164504ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:46.839035Z","caller":"traceutil/trace.go:171","msg":"trace[1169253091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24716; }","duration":"289.913136ms","start":"2026-04-07T00:53:46.549112Z","end":"2026-04-07T00:53:46.839025Z","steps":["trace[1169253091] 'agreement among raft nodes before linearized reading' (duration: 289.806663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:46.840565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.751143ms","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-04-07T00:53:46.8406Z","caller":"traceutil/trace.go:171","msg":"trace[978923188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24716; }","duration":"219.798154ms","start":"2026-04-07T00:53:46.620793Z","end":"2026-04-07T00:53:46.840592Z","steps":["trace[978923188] 'agreement among raft nodes before linearized reading' (duration: 219.679011ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:47.083596Z","caller":"traceutil/trace.go:171","msg":"trace[232063037] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"156.438507ms","start":"2026-04-07T00:53:46.92713Z","end":"2026-04-07T00:53:47.083569Z","steps":["trace[232063037] 'process raft request' (duration: 59.263636ms)","trace[232063037] 'compare' (duration: 97.035117ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:47.301353Z","caller":"traceutil/trace.go:171","msg":"trace[1027062132] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"359.455463ms","start":"2026-04-07T00:53:46.941828Z","end":"2026-04-07T00:53:47.301283Z","steps":["trace[1027062132] 'process raft request' (duration: 347.183549ms)","trace[1027062132] 'compare' (duration: 12.09767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:47.301641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:46.941805Z","time spent":"359.717801ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:53:47.549945Z","caller":"traceutil/trace.go:171","msg":"trace[858420331] linearizableReadLoop","detail":"{readStateIndex:25531; appliedIndex:25530; }","duration":"217.231374ms","start":"2026-04-07T00:53:47.332688Z","end":"2026-04-07T00:53:47.549919Z","steps":["trace[858420331] 'read index received' (duration: 129.551483ms)","trace[858420331] 'applied index is now lower than readState.Index' (duration: 87.678561ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:47.550088Z","caller":"traceutil/trace.go:171","msg":"trace[114635124] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"226.318962ms","start":"2026-04-07T00:53:47.323749Z","end":"2026-04-07T00:53:47.550068Z","steps":["trace[114635124] 'process raft request' (duration: 138.549959ms)","trace[114635124] 'compare' (duration: 87.490886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:47.550258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.539094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:47.550339Z","caller":"traceutil/trace.go:171","msg":"trace[1241255742] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:24724; }","duration":"217.656706ms","start":"2026-04-07T00:53:47.33266Z","end":"2026-04-07T00:53:47.550316Z","steps":["trace[1241255742] 'agreement among raft nodes before linearized reading' (duration: 217.513152ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:47.828199Z","caller":"traceutil/trace.go:171","msg":"trace[689205392] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"122.767368ms","start":"2026-04-07T00:53:47.705405Z","end":"2026-04-07T00:53:47.828172Z","steps":["trace[689205392] 'process raft request' (duration: 122.604754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:48.18417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.888913ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-04-07T00:53:48.184396Z","caller":"traceutil/trace.go:171","msg":"trace[2063977517] linearizableReadLoop","detail":"{readStateIndex:25534; appliedIndex:25532; }","duration":"265.759458ms","start":"2026-04-07T00:53:47.918621Z","end":"2026-04-07T00:53:48.18438Z","steps":["trace[2063977517] 'read index received' (duration: 51.665949ms)","trace[2063977517] 'applied index is now lower than readState.Index' (duration: 214.092689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:48.184382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:47.833419Z","time spent":"350.957022ms","remote":"127.0.0.1:36744","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T00:53:48.184658Z","caller":"traceutil/trace.go:171","msg":"trace[2063084979] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"348.368491ms","start":"2026-04-07T00:53:47.836279Z","end":"2026-04-07T00:53:48.184647Z","steps":["trace[2063084979] 'process raft request' (duration: 347.988011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:48.184756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:53:47.836263Z","time spent":"348.432453ms","remote":"127.0.0.1:36970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:53:48.184915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.652026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-07T00:53:48.184973Z","caller":"traceutil/trace.go:171","msg":"trace[1278673757] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24726; }","duration":"246.755059ms","start":"2026-04-07T00:53:47.938199Z","end":"2026-04-07T00:53:48.184954Z","steps":["trace[1278673757] 'agreement among raft nodes before linearized reading' (duration: 246.573554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:48.185117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.492787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-rtb5m\" ","response":"range_response_count:1 size:3397"} {"level":"info","ts":"2026-04-07T00:53:48.18516Z","caller":"traceutil/trace.go:171","msg":"trace[175282233] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-rtb5m; range_end:; response_count:1; response_revision:24726; }","duration":"266.56363ms","start":"2026-04-07T00:53:47.918585Z","end":"2026-04-07T00:53:48.185148Z","steps":["trace[175282233] 'agreement among raft nodes before linearized reading' (duration: 266.427096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:53:48.185182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.626535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:53:48.185229Z","caller":"traceutil/trace.go:171","msg":"trace[1367847322] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:24726; }","duration":"217.711787ms","start":"2026-04-07T00:53:47.967501Z","end":"2026-04-07T00:53:48.185213Z","steps":["trace[1367847322] 'agreement among raft nodes before linearized reading' (duration: 217.643095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:48.430404Z","caller":"traceutil/trace.go:171","msg":"trace[551242401] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"242.984947ms","start":"2026-04-07T00:53:48.187388Z","end":"2026-04-07T00:53:48.430373Z","steps":["trace[551242401] 'process raft request' (duration: 201.911767ms)","trace[551242401] 'compare' (duration: 40.853774ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:53:48.430784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.433445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-07T00:53:48.430848Z","caller":"traceutil/trace.go:171","msg":"trace[1690215368] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24727; }","duration":"241.536397ms","start":"2026-04-07T00:53:48.189296Z","end":"2026-04-07T00:53:48.430833Z","steps":["trace[1690215368] 'agreement among raft nodes before linearized reading' (duration: 241.29902ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:48.43149Z","caller":"traceutil/trace.go:171","msg":"trace[201179688] linearizableReadLoop","detail":"{readStateIndex:25535; appliedIndex:25534; }","duration":"240.974312ms","start":"2026-04-07T00:53:48.189327Z","end":"2026-04-07T00:53:48.430301Z","steps":["trace[201179688] 'read index received' (duration: 199.996225ms)","trace[201179688] 'applied index is now lower than readState.Index' (duration: 40.976307ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:48.453033Z","caller":"traceutil/trace.go:171","msg":"trace[1072938022] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"261.110231ms","start":"2026-04-07T00:53:48.1919Z","end":"2026-04-07T00:53:48.45301Z","steps":["trace[1072938022] 'process raft request' (duration: 260.587206ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:48.453434Z","caller":"traceutil/trace.go:171","msg":"trace[594913402] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"123.170789ms","start":"2026-04-07T00:53:48.330242Z","end":"2026-04-07T00:53:48.453413Z","steps":["trace[594913402] 'process raft request' (duration: 122.388707ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:48.623643Z","caller":"traceutil/trace.go:171","msg":"trace[1565357245] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"163.307574ms","start":"2026-04-07T00:53:48.460303Z","end":"2026-04-07T00:53:48.623611Z","steps":["trace[1565357245] 'process raft request' (duration: 99.673238ms)","trace[1565357245] 'compare' (duration: 63.0626ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:53:48.623669Z","caller":"traceutil/trace.go:171","msg":"trace[1214341231] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"162.746368ms","start":"2026-04-07T00:53:48.460897Z","end":"2026-04-07T00:53:48.623644Z","steps":["trace[1214341231] 'process raft request' (duration: 162.393989ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:09.355285Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000694d.wal"} {"level":"info","ts":"2026-04-07T00:56:34.785919Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23480} {"level":"info","ts":"2026-04-07T00:56:34.922546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23480,"took":"132.37093ms","hash":2201506228,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":36700160,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-07T00:56:34.922626Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2201506228,"revision":23480,"compact-revision":20330} {"level":"info","ts":"2026-04-07T00:57:35.66794Z","caller":"traceutil/trace.go:171","msg":"trace[541848310] linearizableReadLoop","detail":"{readStateIndex:27912; appliedIndex:27911; }","duration":"110.931886ms","start":"2026-04-07T00:57:35.556977Z","end":"2026-04-07T00:57:35.667909Z","steps":["trace[541848310] 'read index received' (duration: 110.816553ms)","trace[541848310] 'applied index is now lower than readState.Index' (duration: 114.643µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:35.66802Z","caller":"traceutil/trace.go:171","msg":"trace[1671049143] transaction","detail":"{read_only:false; response_revision:27047; number_of_response:1; }","duration":"122.317216ms","start":"2026-04-07T00:57:35.545632Z","end":"2026-04-07T00:57:35.667949Z","steps":["trace[1671049143] 'process raft request' (duration: 122.157381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:35.668141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.134161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:35.6684Z","caller":"traceutil/trace.go:171","msg":"trace[989699428] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:27047; }","duration":"111.416728ms","start":"2026-04-07T00:57:35.556958Z","end":"2026-04-07T00:57:35.668375Z","steps":["trace[989699428] 'agreement among raft nodes before linearized reading' (duration: 111.072019ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:01:34.793695Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26391} {"level":"info","ts":"2026-04-07T01:01:34.933589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26391,"took":"135.367029ms","hash":4280693715,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":33177600,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T01:01:34.933661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4280693715,"revision":26391,"compact-revision":23480} {"level":"info","ts":"2026-04-07T01:01:57.134296Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d2c3f297b56db0c4","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:01:57.138875Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-07T01:01:57.139255Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-07T01:06:34.801252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28916} {"level":"info","ts":"2026-04-07T01:06:34.936136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28916,"took":"130.331773ms","hash":2416518244,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":30896128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T01:06:34.936205Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2416518244,"revision":28916,"compact-revision":26391} {"level":"info","ts":"2026-04-07T01:09:26.743152Z","caller":"traceutil/trace.go:171","msg":"trace[424560328] transaction","detail":"{read_only:false; response_revision:32465; number_of_response:1; }","duration":"148.655383ms","start":"2026-04-07T01:09:26.594474Z","end":"2026-04-07T01:09:26.74313Z","steps":["trace[424560328] 'process raft request' (duration: 148.54356ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:09:26.744689Z","caller":"traceutil/trace.go:171","msg":"trace[2080279789] transaction","detail":"{read_only:false; response_revision:32466; number_of_response:1; }","duration":"128.105332ms","start":"2026-04-07T01:09:26.616552Z","end":"2026-04-07T01:09:26.744658Z","steps":["trace[2080279789] 'process raft request' (duration: 127.453005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:09:27.550652Z","caller":"traceutil/trace.go:171","msg":"trace[1454882106] linearizableReadLoop","detail":"{readStateIndex:33488; appliedIndex:33487; }","duration":"128.701979ms","start":"2026-04-07T01:09:27.421931Z","end":"2026-04-07T01:09:27.550633Z","steps":["trace[1454882106] 'read index received' (duration: 128.494103ms)","trace[1454882106] 'applied index is now lower than readState.Index' (duration: 204.296µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:09:27.550791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.830012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T01:09:27.550758Z","caller":"traceutil/trace.go:171","msg":"trace[1839575884] transaction","detail":"{read_only:false; response_revision:32468; number_of_response:1; }","duration":"169.735667ms","start":"2026-04-07T01:09:27.380986Z","end":"2026-04-07T01:09:27.550722Z","steps":["trace[1839575884] 'process raft request' (duration: 169.46327ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:09:27.550817Z","caller":"traceutil/trace.go:171","msg":"trace[1693692053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:32468; }","duration":"128.921404ms","start":"2026-04-07T01:09:27.421888Z","end":"2026-04-07T01:09:27.55081Z","steps":["trace[1693692053] 'agreement among raft nodes before linearized reading' (duration: 128.839812ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:11:34.806551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31167} {"level":"info","ts":"2026-04-07T01:11:34.928207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31167,"took":"117.927595ms","hash":3364547401,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":30982144,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T01:11:34.928273Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3364547401,"revision":31167,"compact-revision":28916} {"level":"info","ts":"2026-04-07T01:16:34.812379Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33499} {"level":"info","ts":"2026-04-07T01:16:34.953809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33499,"took":"137.798579ms","hash":1413715441,"current-db-size-bytes":57085952,"current-db-size":"57 MB","current-db-size-in-use-bytes":32288768,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:16:34.953883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1413715441,"revision":33499,"compact-revision":31167} {"level":"info","ts":"2026-04-07T01:21:34.81889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35730} {"level":"info","ts":"2026-04-07T01:21:34.960671Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35730,"took":"138.315083ms","hash":3849354115,"current-db-size-bytes":58413056,"current-db-size":"58 MB","current-db-size-in-use-bytes":31465472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:21:34.960744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3849354115,"revision":35730,"compact-revision":33499} {"level":"info","ts":"2026-04-07T01:23:22.471536Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d2c3f297b56db0c4","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:23:22.475724Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-07T01:23:22.475915Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}