{"level":"warn","ts":"2026-06-22T01:57:10.40258Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-22T01:57:10.402664Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-06-22T01:57:10.402724Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-22T01:57:10.402743Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-22T01:57:10.402763Z","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-06-22T01:57:10.40339Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-06-22T01:57:10.40349Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-06-22T01:57:10.438727Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"35.032437ms"} {"level":"info","ts":"2026-06-22T01:57:10.448098Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-06-22T01:57:10.450111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-06-22T01:57:10.450158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-06-22T01:57:10.450212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-22T01:57:10.450242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-06-22T01:57:10.450304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-06-22T01:57:10.461021Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-22T01:57:10.462307Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-22T01:57:10.463278Z","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-06-22T01:57:10.465794Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-22T01:57:10.465998Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-22T01:57:10.466099Z","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-06-22T01:57:10.466206Z","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-06-22T01:57:10.466223Z","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-06-22T01:57:10.468198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-06-22T01:57:10.468756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-06-22T01:57:10.471178Z","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-06-22T01:57:10.47139Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-22T01:57:10.471518Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-06-22T01:57:10.471599Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-22T01:57:10.471649Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-22T01:57:11.051239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-06-22T01:57:11.051314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-22T01:57:11.051359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-06-22T01:57:11.051378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-06-22T01:57:11.051389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-22T01:57:11.051437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-06-22T01:57:11.051456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-06-22T01:57:11.052617Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-06-22T01:57:11.052664Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-22T01:57:11.052757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-22T01:57:11.052881Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-22T01:57:11.052961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-22T01:57:11.05338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-22T01:57:11.053567Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-06-22T01:57:11.053697Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-22T01:57:11.053738Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-22T01:57:11.056628Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-22T01:57:11.061731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"warn","ts":"2026-06-22T01:57:49.21493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.892684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-22T01:57:49.216175Z","caller":"traceutil/trace.go:171","msg":"trace[69074245] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:526; }","duration":"196.133061ms","start":"2026-06-22T01:57:49.018943Z","end":"2026-06-22T01:57:49.215076Z","steps":["trace[69074245] 'range keys from in-memory index tree' (duration: 195.789212ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:57:55.414127Z","caller":"traceutil/trace.go:171","msg":"trace[596337257] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"106.784163ms","start":"2026-06-22T01:57:55.30732Z","end":"2026-06-22T01:57:55.414104Z","steps":["trace[596337257] 'process raft request' (duration: 106.63649ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:57:55.474439Z","caller":"traceutil/trace.go:171","msg":"trace[1621797409] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"159.728495ms","start":"2026-06-22T01:57:55.314675Z","end":"2026-06-22T01:57:55.474403Z","steps":["trace[1621797409] 'process raft request' (duration: 159.379437ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:57:55.47592Z","caller":"traceutil/trace.go:171","msg":"trace[1384194874] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"131.451954ms","start":"2026-06-22T01:57:55.344428Z","end":"2026-06-22T01:57:55.47588Z","steps":["trace[1384194874] 'process raft request' (duration: 130.591794ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:57:55.808136Z","caller":"traceutil/trace.go:171","msg":"trace[549756193] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"268.029425ms","start":"2026-06-22T01:57:55.54008Z","end":"2026-06-22T01:57:55.808109Z","steps":["trace[549756193] 'read index received' (duration: 267.730298ms)","trace[549756193] 'applied index is now lower than readState.Index' (duration: 297.687µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T01:57:55.808197Z","caller":"traceutil/trace.go:171","msg":"trace[1692116297] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"289.309133ms","start":"2026-06-22T01:57:55.518866Z","end":"2026-06-22T01:57:55.808175Z","steps":["trace[1692116297] 'process raft request' (duration: 289.071438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T01:57:55.80831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.20484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-22T01:57:55.808353Z","caller":"traceutil/trace.go:171","msg":"trace[1265053770] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:625; }","duration":"268.296581ms","start":"2026-06-22T01:57:55.540042Z","end":"2026-06-22T01:57:55.808339Z","steps":["trace[1265053770] 'agreement among raft nodes before linearized reading' (duration: 268.206929ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:57:56.346792Z","caller":"traceutil/trace.go:171","msg":"trace[67050086] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"113.408385ms","start":"2026-06-22T01:57:56.233351Z","end":"2026-06-22T01:57:56.346759Z","steps":["trace[67050086] 'process raft request' (duration: 112.666917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T01:58:13.174793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.769016ms","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-06-22T01:58:13.174888Z","caller":"traceutil/trace.go:171","msg":"trace[1526964123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"116.92208ms","start":"2026-06-22T01:58:13.057946Z","end":"2026-06-22T01:58:13.174868Z","steps":["trace[1526964123] 'range keys from in-memory index tree' (duration: 116.510099ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:58:31.777506Z","caller":"traceutil/trace.go:171","msg":"trace[1852838244] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"140.345646ms","start":"2026-06-22T01:58:31.63712Z","end":"2026-06-22T01:58:31.777466Z","steps":["trace[1852838244] 'process raft request' (duration: 140.162652ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:59:28.573628Z","caller":"traceutil/trace.go:171","msg":"trace[267683377] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"242.409324ms","start":"2026-06-22T01:59:28.331182Z","end":"2026-06-22T01:59:28.573592Z","steps":["trace[267683377] 'process raft request' (duration: 242.123498ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T01:59:28.638272Z","caller":"traceutil/trace.go:171","msg":"trace[413252540] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1900; }","duration":"162.99013ms","start":"2026-06-22T01:59:28.475247Z","end":"2026-06-22T01:59:28.638237Z","steps":["trace[413252540] 'read index received' (duration: 98.074086ms)","trace[413252540] 'applied index is now lower than readState.Index' (duration: 64.914724ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T01:59:28.638368Z","caller":"traceutil/trace.go:171","msg":"trace[870691140] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"295.766873ms","start":"2026-06-22T01:59:28.342553Z","end":"2026-06-22T01:59:28.63832Z","steps":["trace[870691140] 'process raft request' (duration: 295.480797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T01:59:28.638451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.180855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-22T01:59:28.638708Z","caller":"traceutil/trace.go:171","msg":"trace[1618398593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1848; }","duration":"163.514883ms","start":"2026-06-22T01:59:28.475176Z","end":"2026-06-22T01:59:28.638691Z","steps":["trace[1618398593] 'agreement among raft nodes before linearized reading' (duration: 163.200405ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:00:11.733595Z","caller":"traceutil/trace.go:171","msg":"trace[294655976] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"112.055246ms","start":"2026-06-22T02:00:11.621499Z","end":"2026-06-22T02:00:11.733554Z","steps":["trace[294655976] 'process raft request' (duration: 104.939769ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:00:12.162519Z","caller":"traceutil/trace.go:171","msg":"trace[1680190406] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"113.480016ms","start":"2026-06-22T02:00:12.048992Z","end":"2026-06-22T02:00:12.162472Z","steps":["trace[1680190406] 'process raft request' (duration: 113.283072ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:00:56.875342Z","caller":"traceutil/trace.go:171","msg":"trace[2058640154] transaction","detail":"{read_only:false; response_revision:2562; number_of_response:1; }","duration":"191.01573ms","start":"2026-06-22T02:00:56.684291Z","end":"2026-06-22T02:00:56.875307Z","steps":["trace[2058640154] 'process raft request' (duration: 189.058868ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:03:45.334727Z","caller":"traceutil/trace.go:171","msg":"trace[2072312202] transaction","detail":"{read_only:false; response_revision:4290; number_of_response:1; }","duration":"100.449511ms","start":"2026-06-22T02:03:45.234227Z","end":"2026-06-22T02:03:45.334676Z","steps":["trace[2072312202] 'process raft request' (duration: 15.528568ms)","trace[2072312202] 'compare' (duration: 84.706418ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:03:45.406303Z","caller":"traceutil/trace.go:171","msg":"trace[913353587] transaction","detail":"{read_only:false; response_revision:4292; number_of_response:1; }","duration":"138.348022ms","start":"2026-06-22T02:03:45.267891Z","end":"2026-06-22T02:03:45.406239Z","steps":["trace[913353587] 'process raft request' (duration: 138.012245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:03:56.50997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.276478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:790436"} {"level":"info","ts":"2026-06-22T02:03:56.510185Z","caller":"traceutil/trace.go:171","msg":"trace[671918262] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4638; }","duration":"154.599796ms","start":"2026-06-22T02:03:56.355561Z","end":"2026-06-22T02:03:56.51016Z","steps":["trace[671918262] 'range keys from in-memory index tree' (duration: 153.703466ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:03:59.777186Z","caller":"traceutil/trace.go:171","msg":"trace[566527046] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"149.98927ms","start":"2026-06-22T02:03:59.627148Z","end":"2026-06-22T02:03:59.777138Z","steps":["trace[566527046] 'process raft request' (duration: 146.011019ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:03:59.963586Z","caller":"traceutil/trace.go:171","msg":"trace[1353113481] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"107.213266ms","start":"2026-06-22T02:03:59.856316Z","end":"2026-06-22T02:03:59.963529Z","steps":["trace[1353113481] 'process raft request' (duration: 59.818155ms)","trace[1353113481] 'compare' (duration: 47.120935ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:04:13.421556Z","caller":"traceutil/trace.go:171","msg":"trace[96210147] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"200.637366ms","start":"2026-06-22T02:04:13.220872Z","end":"2026-06-22T02:04:13.42151Z","steps":["trace[96210147] 'process raft request' (duration: 200.296768ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:04:13.755105Z","caller":"traceutil/trace.go:171","msg":"trace[719995491] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"258.900464ms","start":"2026-06-22T02:04:13.496145Z","end":"2026-06-22T02:04:13.755045Z","steps":["trace[719995491] 'read index received' (duration: 258.415873ms)","trace[719995491] 'applied index is now lower than readState.Index' (duration: 483.011µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:04:13.755268Z","caller":"traceutil/trace.go:171","msg":"trace[1520593835] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"294.261308ms","start":"2026-06-22T02:04:13.460955Z","end":"2026-06-22T02:04:13.755216Z","steps":["trace[1520593835] 'process raft request' (duration: 293.679725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:04:13.755333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.155791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-22T02:04:13.755514Z","caller":"traceutil/trace.go:171","msg":"trace[514692594] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4940; }","duration":"259.383685ms","start":"2026-06-22T02:04:13.496101Z","end":"2026-06-22T02:04:13.755485Z","steps":["trace[514692594] 'agreement among raft nodes before linearized reading' (duration: 259.120849ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:04:20.388559Z","caller":"traceutil/trace.go:171","msg":"trace[1917368557] transaction","detail":"{read_only:false; response_revision:5099; number_of_response:1; }","duration":"203.331448ms","start":"2026-06-22T02:04:20.185202Z","end":"2026-06-22T02:04:20.388534Z","steps":["trace[1917368557] 'process raft request' (duration: 132.802816ms)","trace[1917368557] 'compare' (duration: 69.980129ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:07:11.081389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3233} {"level":"info","ts":"2026-06-22T02:07:11.169777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3233,"took":"86.466331ms","hash":2852924493,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":33800192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-22T02:07:11.169855Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2852924493,"revision":3233,"compact-revision":-1} {"level":"info","ts":"2026-06-22T02:10:16.054863Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021ee.wal"} {"level":"info","ts":"2026-06-22T02:10:20.374111Z","caller":"traceutil/trace.go:171","msg":"trace[289362203] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"207.337602ms","start":"2026-06-22T02:10:20.166741Z","end":"2026-06-22T02:10:20.374079Z","steps":["trace[289362203] 'process raft request' (duration: 207.023416ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:10:20.507955Z","caller":"traceutil/trace.go:171","msg":"trace[1256027463] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"128.729174ms","start":"2026-06-22T02:10:20.379197Z","end":"2026-06-22T02:10:20.507926Z","steps":["trace[1256027463] 'process raft request' (duration: 128.422108ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:10:32.190743Z","caller":"traceutil/trace.go:171","msg":"trace[696860110] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"343.378012ms","start":"2026-06-22T02:10:31.847329Z","end":"2026-06-22T02:10:32.190707Z","steps":["trace[696860110] 'process raft request' (duration: 343.165827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:10:32.191576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:10:31.847301Z","time spent":"343.562515ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:10:32.323619Z","caller":"traceutil/trace.go:171","msg":"trace[944365676] linearizableReadLoop","detail":"{readStateIndex:8802; appliedIndex:8800; }","duration":"414.381429ms","start":"2026-06-22T02:10:31.909206Z","end":"2026-06-22T02:10:32.323588Z","steps":["trace[944365676] 'read index received' (duration: 281.349806ms)","trace[944365676] 'applied index is now lower than readState.Index' (duration: 133.030643ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:10:32.323697Z","caller":"traceutil/trace.go:171","msg":"trace[270850805] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"425.09317ms","start":"2026-06-22T02:10:31.898562Z","end":"2026-06-22T02:10:32.323655Z","steps":["trace[270850805] 'process raft request' (duration: 424.776593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:10:32.323844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.621615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-22T02:10:32.323869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:10:31.898525Z","time spent":"425.254104ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:10:32.32389Z","caller":"traceutil/trace.go:171","msg":"trace[64318332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8422; }","duration":"414.711096ms","start":"2026-06-22T02:10:31.909165Z","end":"2026-06-22T02:10:32.323876Z","steps":["trace[64318332] 'agreement among raft nodes before linearized reading' (duration: 414.540012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:10:32.323935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:10:31.909149Z","time spent":"414.777628ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-22T02:10:32.981547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.807761ms","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-06-22T02:10:32.981626Z","caller":"traceutil/trace.go:171","msg":"trace[784480416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8426; }","duration":"254.951634ms","start":"2026-06-22T02:10:32.726657Z","end":"2026-06-22T02:10:32.981608Z","steps":["trace[784480416] 'range keys from in-memory index tree' (duration: 254.613146ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:11:00.490895Z","caller":"traceutil/trace.go:171","msg":"trace[387190120] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"157.278858ms","start":"2026-06-22T02:11:00.333572Z","end":"2026-06-22T02:11:00.490851Z","steps":["trace[387190120] 'process raft request' (duration: 157.014022ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:11:00.671557Z","caller":"traceutil/trace.go:171","msg":"trace[1907400930] transaction","detail":"{read_only:false; response_revision:8665; number_of_response:1; }","duration":"151.353744ms","start":"2026-06-22T02:11:00.520176Z","end":"2026-06-22T02:11:00.671529Z","steps":["trace[1907400930] 'process raft request' (duration: 147.61257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:11:13.898616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.707718ms","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-06-22T02:11:13.89873Z","caller":"traceutil/trace.go:171","msg":"trace[1522878033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8798; }","duration":"113.881952ms","start":"2026-06-22T02:11:13.784821Z","end":"2026-06-22T02:11:13.898703Z","steps":["trace[1522878033] 'range keys from in-memory index tree' (duration: 113.500832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:11:14.164818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.365644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2s2nf\" ","response":"range_response_count:1 size:4614"} {"level":"info","ts":"2026-06-22T02:11:14.164916Z","caller":"traceutil/trace.go:171","msg":"trace[531655574] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2s2nf; range_end:; response_count:1; response_revision:8800; }","duration":"151.522507ms","start":"2026-06-22T02:11:14.013375Z","end":"2026-06-22T02:11:14.164897Z","steps":["trace[531655574] 'range keys from in-memory index tree' (duration: 151.18576ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:11:14.165013Z","caller":"traceutil/trace.go:171","msg":"trace[440489901] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"150.055675ms","start":"2026-06-22T02:11:14.01491Z","end":"2026-06-22T02:11:14.164966Z","steps":["trace[440489901] 'process raft request' (duration: 146.958885ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:11:14.188603Z","caller":"traceutil/trace.go:171","msg":"trace[404804549] transaction","detail":"{read_only:false; response_revision:8802; number_of_response:1; }","duration":"164.98935ms","start":"2026-06-22T02:11:14.023577Z","end":"2026-06-22T02:11:14.188567Z","steps":["trace[404804549] 'process raft request' (duration: 164.718964ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:11:14.188763Z","caller":"traceutil/trace.go:171","msg":"trace[175059419] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"154.984304ms","start":"2026-06-22T02:11:14.033744Z","end":"2026-06-22T02:11:14.188729Z","steps":["trace[175059419] 'process raft request' (duration: 154.756169ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:12:11.086844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6560} {"level":"info","ts":"2026-06-22T02:12:11.185682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6560,"took":"96.677815ms","hash":2470291651,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":26710016,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-22T02:12:11.185776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2470291651,"revision":6560,"compact-revision":3233} {"level":"info","ts":"2026-06-22T02:12:24.688981Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-22T02:12:24.694699Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-22T02:12:24.694821Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-06-22T02:16:02.449081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.807579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-22T02:16:02.449212Z","caller":"traceutil/trace.go:171","msg":"trace[679691664] transaction","detail":"{read_only:false; response_revision:11729; number_of_response:1; }","duration":"182.509928ms","start":"2026-06-22T02:16:02.266674Z","end":"2026-06-22T02:16:02.449184Z","steps":["trace[679691664] 'process raft request' (duration: 19.26778ms)","trace[679691664] 'compare' (duration: 162.656026ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:17:11.092407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9463} {"level":"info","ts":"2026-06-22T02:17:11.176158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9463,"took":"81.632419ms","hash":1521434956,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":28499968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-22T02:17:11.176213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1521434956,"revision":9463,"compact-revision":6560} {"level":"info","ts":"2026-06-22T02:22:11.098508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12296} {"level":"info","ts":"2026-06-22T02:22:11.193357Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12296,"took":"92.171789ms","hash":2309540678,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":25399296,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-22T02:22:11.193441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2309540678,"revision":12296,"compact-revision":9463} {"level":"info","ts":"2026-06-22T02:22:43.857126Z","caller":"traceutil/trace.go:171","msg":"trace[1879697514] linearizableReadLoop","detail":"{readStateIndex:15669; appliedIndex:15668; }","duration":"243.524335ms","start":"2026-06-22T02:22:43.613579Z","end":"2026-06-22T02:22:43.857103Z","steps":["trace[1879697514] 'read index received' (duration: 243.332921ms)","trace[1879697514] 'applied index is now lower than readState.Index' (duration: 190.314µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:22:43.857247Z","caller":"traceutil/trace.go:171","msg":"trace[1755444647] transaction","detail":"{read_only:false; response_revision:14962; number_of_response:1; }","duration":"303.562173ms","start":"2026-06-22T02:22:43.553613Z","end":"2026-06-22T02:22:43.857175Z","steps":["trace[1755444647] 'process raft request' (duration: 303.293077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:22:43.857511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:22:43.553584Z","time spent":"303.790558ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:22:43.85811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.510487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:82 size:714768"} {"level":"info","ts":"2026-06-22T02:22:43.85817Z","caller":"traceutil/trace.go:171","msg":"trace[1119489543] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:82; response_revision:14962; }","duration":"244.633149ms","start":"2026-06-22T02:22:43.613525Z","end":"2026-06-22T02:22:43.858158Z","steps":["trace[1119489543] 'agreement among raft nodes before linearized reading' (duration: 243.687499ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:24:26.845588Z","caller":"traceutil/trace.go:171","msg":"trace[2023631746] transaction","detail":"{read_only:false; response_revision:16313; number_of_response:1; }","duration":"157.729362ms","start":"2026-06-22T02:24:26.687823Z","end":"2026-06-22T02:24:26.845552Z","steps":["trace[2023631746] 'process raft request' (duration: 157.502036ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:24:26.947599Z","caller":"traceutil/trace.go:171","msg":"trace[847504881] linearizableReadLoop","detail":"{readStateIndex:17075; appliedIndex:17073; }","duration":"160.037501ms","start":"2026-06-22T02:24:26.787536Z","end":"2026-06-22T02:24:26.947574Z","steps":["trace[847504881] 'read index received' (duration: 57.827338ms)","trace[847504881] 'applied index is now lower than readState.Index' (duration: 102.208533ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:24:26.947687Z","caller":"traceutil/trace.go:171","msg":"trace[450369323] transaction","detail":"{read_only:false; response_revision:16314; number_of_response:1; }","duration":"257.228375ms","start":"2026-06-22T02:24:26.690419Z","end":"2026-06-22T02:24:26.947647Z","steps":["trace[450369323] 'process raft request' (duration: 254.504496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:24:26.947856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.271946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-22T02:24:26.947949Z","caller":"traceutil/trace.go:171","msg":"trace[211432540] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16315; }","duration":"160.43318ms","start":"2026-06-22T02:24:26.787475Z","end":"2026-06-22T02:24:26.947908Z","steps":["trace[211432540] 'agreement among raft nodes before linearized reading' (duration: 160.281566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:24:26.947966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.890988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-06-22T02:24:26.948047Z","caller":"traceutil/trace.go:171","msg":"trace[1101428069] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:16315; }","duration":"160.054821ms","start":"2026-06-22T02:24:26.787972Z","end":"2026-06-22T02:24:26.948026Z","steps":["trace[1101428069] 'agreement among raft nodes before linearized reading' (duration: 159.807536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:24:26.94824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.810096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-22T02:24:26.948302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.214885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-22T02:24:26.948366Z","caller":"traceutil/trace.go:171","msg":"trace[2067986026] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:16315; }","duration":"160.292906ms","start":"2026-06-22T02:24:26.788057Z","end":"2026-06-22T02:24:26.948349Z","steps":["trace[2067986026] 'agreement among raft nodes before linearized reading' (duration: 160.204924ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:24:26.948308Z","caller":"traceutil/trace.go:171","msg":"trace[940142835] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16315; }","duration":"159.896378ms","start":"2026-06-22T02:24:26.788396Z","end":"2026-06-22T02:24:26.948292Z","steps":["trace[940142835] 'agreement among raft nodes before linearized reading' (duration: 159.736934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:24:26.948401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.31774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5520"} {"level":"info","ts":"2026-06-22T02:24:26.948474Z","caller":"traceutil/trace.go:171","msg":"trace[1798771701] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16315; }","duration":"156.428362ms","start":"2026-06-22T02:24:26.792026Z","end":"2026-06-22T02:24:26.948455Z","steps":["trace[1798771701] 'agreement among raft nodes before linearized reading' (duration: 156.202837ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:27:11.10659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14734} {"level":"info","ts":"2026-06-22T02:27:11.241008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14734,"took":"131.093921ms","hash":2505993359,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":30707712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-22T02:27:11.241069Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2505993359,"revision":14734,"compact-revision":12296} {"level":"info","ts":"2026-06-22T02:29:38.658359Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-22T02:29:38.66328Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-22T02:29:38.663372Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-22T02:30:04.751597Z","caller":"traceutil/trace.go:171","msg":"trace[49400093] transaction","detail":"{read_only:false; response_revision:19328; number_of_response:1; }","duration":"159.505429ms","start":"2026-06-22T02:30:04.592064Z","end":"2026-06-22T02:30:04.751569Z","steps":["trace[49400093] 'process raft request' (duration: 159.283614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:30:10.317703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.73656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-cmdzh\" ","response":"range_response_count:1 size:20728"} {"level":"warn","ts":"2026-06-22T02:30:10.317786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.829637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-hzsfq\" ","response":"range_response_count:1 size:9781"} {"level":"info","ts":"2026-06-22T02:30:10.317826Z","caller":"traceutil/trace.go:171","msg":"trace[2122501692] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-hzsfq; range_end:; response_count:1; response_revision:19373; }","duration":"342.98765ms","start":"2026-06-22T02:30:09.974828Z","end":"2026-06-22T02:30:10.317816Z","steps":["trace[2122501692] 'range keys from in-memory index tree' (duration: 342.261494ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:30:10.317823Z","caller":"traceutil/trace.go:171","msg":"trace[1669494239] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-cmdzh; range_end:; response_count:1; response_revision:19373; }","duration":"179.905912ms","start":"2026-06-22T02:30:10.137897Z","end":"2026-06-22T02:30:10.317803Z","steps":["trace[1669494239] 'range keys from in-memory index tree' (duration: 179.559155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:30:10.317846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:30:09.974803Z","time spent":"343.037331ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9803,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-hzsfq\" "} {"level":"info","ts":"2026-06-22T02:30:10.590981Z","caller":"traceutil/trace.go:171","msg":"trace[272191286] linearizableReadLoop","detail":"{readStateIndex:20288; appliedIndex:20287; }","duration":"231.567077ms","start":"2026-06-22T02:30:10.359377Z","end":"2026-06-22T02:30:10.590944Z","steps":["trace[272191286] 'read index received' (duration: 231.184539ms)","trace[272191286] 'applied index is now lower than readState.Index' (duration: 380.928µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:30:10.591103Z","caller":"traceutil/trace.go:171","msg":"trace[740376295] transaction","detail":"{read_only:false; response_revision:19375; number_of_response:1; }","duration":"252.953772ms","start":"2026-06-22T02:30:10.338103Z","end":"2026-06-22T02:30:10.591057Z","steps":["trace[740376295] 'process raft request' (duration: 252.605505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:30:10.591174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.378998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-22T02:30:10.591242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.345903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:30:10.59125Z","caller":"traceutil/trace.go:171","msg":"trace[537291709] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:19375; }","duration":"257.528131ms","start":"2026-06-22T02:30:10.333704Z","end":"2026-06-22T02:30:10.591232Z","steps":["trace[537291709] 'agreement among raft nodes before linearized reading' (duration: 257.380358ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:30:10.591295Z","caller":"traceutil/trace.go:171","msg":"trace[1529263387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19375; }","duration":"117.468295ms","start":"2026-06-22T02:30:10.473813Z","end":"2026-06-22T02:30:10.591281Z","steps":["trace[1529263387] 'agreement among raft nodes before linearized reading' (duration: 117.354443ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:32:11.113921Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17829} {"level":"info","ts":"2026-06-22T02:32:11.229219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17829,"took":"111.353142ms","hash":1242558256,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":31531008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-22T02:32:11.229304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1242558256,"revision":17829,"compact-revision":14734} {"level":"info","ts":"2026-06-22T02:37:07.820554Z","caller":"traceutil/trace.go:171","msg":"trace[1414071941] transaction","detail":"{read_only:false; response_revision:23399; number_of_response:1; }","duration":"140.095006ms","start":"2026-06-22T02:37:07.680416Z","end":"2026-06-22T02:37:07.820511Z","steps":["trace[1414071941] 'process raft request' (duration: 139.794009ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:37:08.114428Z","caller":"traceutil/trace.go:171","msg":"trace[1666486440] transaction","detail":"{read_only:false; response_revision:23400; number_of_response:1; }","duration":"285.478706ms","start":"2026-06-22T02:37:07.828924Z","end":"2026-06-22T02:37:08.114403Z","steps":["trace[1666486440] 'process raft request' (duration: 212.208833ms)","trace[1666486440] 'compare' (duration: 73.174721ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:37:08.156061Z","caller":"traceutil/trace.go:171","msg":"trace[1612489265] linearizableReadLoop","detail":"{readStateIndex:24505; appliedIndex:24503; }","duration":"111.486974ms","start":"2026-06-22T02:37:08.04454Z","end":"2026-06-22T02:37:08.156027Z","steps":["trace[1612489265] 'read index received' (duration: 111.243599ms)","trace[1612489265] 'applied index is now lower than readState.Index' (duration: 242.405µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:37:08.156169Z","caller":"traceutil/trace.go:171","msg":"trace[760624258] transaction","detail":"{read_only:false; response_revision:23401; number_of_response:1; }","duration":"327.112222ms","start":"2026-06-22T02:37:07.829008Z","end":"2026-06-22T02:37:08.15612Z","steps":["trace[760624258] 'process raft request' (duration: 326.825246ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:37:08.156189Z","caller":"traceutil/trace.go:171","msg":"trace[1699240086] transaction","detail":"{read_only:false; response_revision:23402; number_of_response:1; }","duration":"182.871306ms","start":"2026-06-22T02:37:07.973281Z","end":"2026-06-22T02:37:08.156153Z","steps":["trace[1699240086] 'process raft request' (duration: 182.694302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:37:08.156311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.748ms","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-06-22T02:37:08.156351Z","caller":"traceutil/trace.go:171","msg":"trace[1578608276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23402; }","duration":"111.841282ms","start":"2026-06-22T02:37:08.044498Z","end":"2026-06-22T02:37:08.156339Z","steps":["trace[1578608276] 'agreement among raft nodes before linearized reading' (duration: 111.682708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:37:08.156413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:37:07.828993Z","time spent":"327.250635ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:37:08.306541Z","caller":"traceutil/trace.go:171","msg":"trace[1769779702] transaction","detail":"{read_only:false; response_revision:23404; number_of_response:1; }","duration":"144.91062ms","start":"2026-06-22T02:37:08.161608Z","end":"2026-06-22T02:37:08.306519Z","steps":["trace[1769779702] 'process raft request' (duration: 144.840329ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:37:08.306599Z","caller":"traceutil/trace.go:171","msg":"trace[1266972151] transaction","detail":"{read_only:false; response_revision:23403; number_of_response:1; }","duration":"145.423782ms","start":"2026-06-22T02:37:08.161133Z","end":"2026-06-22T02:37:08.306557Z","steps":["trace[1266972151] 'process raft request' (duration: 106.355073ms)","trace[1266972151] 'compare' (duration: 38.825464ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:37:11.123326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20723} {"level":"info","ts":"2026-06-22T02:37:11.239345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20723,"took":"111.368651ms","hash":3166147606,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":32366592,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-22T02:37:11.239457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3166147606,"revision":20723,"compact-revision":17829} {"level":"info","ts":"2026-06-22T02:40:43.534292Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a32.wal"} {"level":"info","ts":"2026-06-22T02:40:50.048131Z","caller":"traceutil/trace.go:171","msg":"trace[1662437037] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"172.999041ms","start":"2026-06-22T02:40:49.87506Z","end":"2026-06-22T02:40:50.048059Z","steps":["trace[1662437037] 'process raft request' (duration: 172.718254ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:42:11.130109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23420} {"level":"info","ts":"2026-06-22T02:42:11.24088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23420,"took":"106.565347ms","hash":1807766493,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":45494272,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-06-22T02:42:11.240968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1807766493,"revision":23420,"compact-revision":20723} {"level":"info","ts":"2026-06-22T02:42:27.058059Z","caller":"traceutil/trace.go:171","msg":"trace[1142961151] transaction","detail":"{read_only:false; response_revision:27229; number_of_response:1; }","duration":"129.665329ms","start":"2026-06-22T02:42:26.928361Z","end":"2026-06-22T02:42:27.058026Z","steps":["trace[1142961151] 'process raft request' (duration: 129.451904ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:43:11.430079Z","caller":"traceutil/trace.go:171","msg":"trace[58440881] transaction","detail":"{read_only:false; response_revision:27730; number_of_response:1; }","duration":"126.424229ms","start":"2026-06-22T02:43:11.30363Z","end":"2026-06-22T02:43:11.430054Z","steps":["trace[58440881] 'process raft request' (duration: 118.172949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:43:11.430547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.070496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-22T02:43:11.430638Z","caller":"traceutil/trace.go:171","msg":"trace[84428561] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27730; }","duration":"100.217459ms","start":"2026-06-22T02:43:11.330401Z","end":"2026-06-22T02:43:11.430618Z","steps":["trace[84428561] 'agreement among raft nodes before linearized reading' (duration: 100.010114ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:23.655897Z","caller":"traceutil/trace.go:171","msg":"trace[2104082373] transaction","detail":"{read_only:false; response_revision:28523; number_of_response:1; }","duration":"138.369229ms","start":"2026-06-22T02:44:23.517482Z","end":"2026-06-22T02:44:23.655851Z","steps":["trace[2104082373] 'process raft request' (duration: 138.011871ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:23.996141Z","caller":"traceutil/trace.go:171","msg":"trace[1209070227] transaction","detail":"{read_only:false; response_revision:28527; number_of_response:1; }","duration":"178.631054ms","start":"2026-06-22T02:44:23.817478Z","end":"2026-06-22T02:44:23.996109Z","steps":["trace[1209070227] 'process raft request' (duration: 178.421929ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:24.008503Z","caller":"traceutil/trace.go:171","msg":"trace[1105756276] linearizableReadLoop","detail":"{readStateIndex:29847; appliedIndex:29845; }","duration":"112.007885ms","start":"2026-06-22T02:44:23.896473Z","end":"2026-06-22T02:44:24.008481Z","steps":["trace[1105756276] 'read index received' (duration: 99.498803ms)","trace[1105756276] 'applied index is now lower than readState.Index' (duration: 12.507902ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:24.008581Z","caller":"traceutil/trace.go:171","msg":"trace[497454945] transaction","detail":"{read_only:false; response_revision:28528; number_of_response:1; }","duration":"190.918442ms","start":"2026-06-22T02:44:23.817599Z","end":"2026-06-22T02:44:24.008518Z","steps":["trace[497454945] 'process raft request' (duration: 190.681097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:24.008705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.213219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-22T02:44:24.00874Z","caller":"traceutil/trace.go:171","msg":"trace[1534126621] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:28529; }","duration":"112.308041ms","start":"2026-06-22T02:44:23.896419Z","end":"2026-06-22T02:44:24.008727Z","steps":["trace[1534126621] 'agreement among raft nodes before linearized reading' (duration: 112.188999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:24.300901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.678031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:44:24.30114Z","caller":"traceutil/trace.go:171","msg":"trace[1667777594] transaction","detail":"{read_only:false; response_revision:28530; number_of_response:1; }","duration":"286.269953ms","start":"2026-06-22T02:44:24.014848Z","end":"2026-06-22T02:44:24.301118Z","steps":["trace[1667777594] 'process raft request' (duration: 138.248515ms)","trace[1667777594] 'compare' (duration: 147.564949ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:24.302443Z","caller":"traceutil/trace.go:171","msg":"trace[486243956] transaction","detail":"{read_only:false; response_revision:28531; number_of_response:1; }","duration":"287.309877ms","start":"2026-06-22T02:44:24.015094Z","end":"2026-06-22T02:44:24.302404Z","steps":["trace[486243956] 'process raft request' (duration: 287.098012ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:24.30266Z","caller":"traceutil/trace.go:171","msg":"trace[296672026] transaction","detail":"{read_only:false; response_revision:28532; number_of_response:1; }","duration":"287.45251ms","start":"2026-06-22T02:44:24.015178Z","end":"2026-06-22T02:44:24.302631Z","steps":["trace[296672026] 'process raft request' (duration: 287.130232ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:24.302864Z","caller":"traceutil/trace.go:171","msg":"trace[1506834540] transaction","detail":"{read_only:false; response_revision:28533; number_of_response:1; }","duration":"286.904208ms","start":"2026-06-22T02:44:24.015951Z","end":"2026-06-22T02:44:24.302855Z","steps":["trace[1506834540] 'process raft request' (duration: 286.387797ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:24.431847Z","caller":"traceutil/trace.go:171","msg":"trace[1196472157] transaction","detail":"{read_only:false; response_revision:28534; number_of_response:1; }","duration":"139.494233ms","start":"2026-06-22T02:44:24.2923Z","end":"2026-06-22T02:44:24.431794Z","steps":["trace[1196472157] 'process raft request' (duration: 136.938627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:29.073977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.380728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:44:29.07406Z","caller":"traceutil/trace.go:171","msg":"trace[1198798175] linearizableReadLoop","detail":"{readStateIndex:29900; appliedIndex:29899; }","duration":"171.265994ms","start":"2026-06-22T02:44:28.902779Z","end":"2026-06-22T02:44:29.074045Z","steps":["trace[1198798175] 'read index received' (duration: 15.591579ms)","trace[1198798175] 'applied index is now lower than readState.Index' (duration: 155.673194ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:29.074212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.453347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:29.074146Z","caller":"traceutil/trace.go:171","msg":"trace[1320278686] transaction","detail":"{read_only:false; response_revision:28579; number_of_response:1; }","duration":"239.664391ms","start":"2026-06-22T02:44:28.834419Z","end":"2026-06-22T02:44:29.074083Z","steps":["trace[1320278686] 'process raft request' (duration: 84.098819ms)","trace[1320278686] 'compare' (duration: 155.193144ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:29.074241Z","caller":"traceutil/trace.go:171","msg":"trace[2035018244] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:28579; }","duration":"171.486927ms","start":"2026-06-22T02:44:28.902745Z","end":"2026-06-22T02:44:29.074232Z","steps":["trace[2035018244] 'agreement among raft nodes before linearized reading' (duration: 171.379925ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:29.837597Z","caller":"traceutil/trace.go:171","msg":"trace[1590044658] transaction","detail":"{read_only:false; response_revision:28580; number_of_response:1; }","duration":"103.338576ms","start":"2026-06-22T02:44:29.734223Z","end":"2026-06-22T02:44:29.837562Z","steps":["trace[1590044658] 'process raft request' (duration: 103.112241ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:29.996871Z","caller":"traceutil/trace.go:171","msg":"trace[1685354820] linearizableReadLoop","detail":"{readStateIndex:29903; appliedIndex:29902; }","duration":"151.645117ms","start":"2026-06-22T02:44:29.845197Z","end":"2026-06-22T02:44:29.996842Z","steps":["trace[1685354820] 'read index received' (duration: 136.387866ms)","trace[1685354820] 'applied index is now lower than readState.Index' (duration: 15.256311ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:29.997193Z","caller":"traceutil/trace.go:171","msg":"trace[1682809685] transaction","detail":"{read_only:false; response_revision:28581; number_of_response:1; }","duration":"255.048675ms","start":"2026-06-22T02:44:29.742124Z","end":"2026-06-22T02:44:29.997173Z","steps":["trace[1682809685] 'process raft request' (duration: 239.544698ms)","trace[1682809685] 'compare' (duration: 14.72408ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:29.997545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.332382ms","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-06-22T02:44:29.997629Z","caller":"traceutil/trace.go:171","msg":"trace[1527413739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28581; }","duration":"152.482025ms","start":"2026-06-22T02:44:29.845129Z","end":"2026-06-22T02:44:29.997611Z","steps":["trace[1527413739] 'agreement among raft nodes before linearized reading' (duration: 152.241089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.41623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.407685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:44:30.416377Z","caller":"traceutil/trace.go:171","msg":"trace[2090766166] transaction","detail":"{read_only:false; response_revision:28582; number_of_response:1; }","duration":"413.832677ms","start":"2026-06-22T02:44:30.00252Z","end":"2026-06-22T02:44:30.416353Z","steps":["trace[2090766166] 'process raft request' (duration: 237.186767ms)","trace[2090766166] 'compare' (duration: 176.16855ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:30.416446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.002495Z","time spent":"413.921189ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:30.430428Z","caller":"traceutil/trace.go:171","msg":"trace[1008213946] linearizableReadLoop","detail":"{readStateIndex:29905; appliedIndex:29903; }","duration":"375.406012ms","start":"2026-06-22T02:44:30.05499Z","end":"2026-06-22T02:44:30.430396Z","steps":["trace[1008213946] 'read index received' (duration: 185.094444ms)","trace[1008213946] 'applied index is now lower than readState.Index' (duration: 190.310488ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:30.430602Z","caller":"traceutil/trace.go:171","msg":"trace[1393522033] transaction","detail":"{read_only:false; response_revision:28583; number_of_response:1; }","duration":"387.702468ms","start":"2026-06-22T02:44:30.04282Z","end":"2026-06-22T02:44:30.430523Z","steps":["trace[1393522033] 'process raft request' (duration: 387.420882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.430707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.703238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-22T02:44:30.430746Z","caller":"traceutil/trace.go:171","msg":"trace[1617866275] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28583; }","duration":"375.8215ms","start":"2026-06-22T02:44:30.054911Z","end":"2026-06-22T02:44:30.430732Z","steps":["trace[1617866275] 'agreement among raft nodes before linearized reading' (duration: 375.630336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.430774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.054879Z","time spent":"375.887422ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-22T02:44:30.430848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.042779Z","time spent":"387.939034ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:30.500174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.376916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-22T02:44:30.500263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.144188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-22T02:44:30.500288Z","caller":"traceutil/trace.go:171","msg":"trace[429291405] 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:28584; }","duration":"171.57075ms","start":"2026-06-22T02:44:30.328695Z","end":"2026-06-22T02:44:30.500266Z","steps":["trace[429291405] 'agreement among raft nodes before linearized reading' (duration: 171.331425ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:30.500322Z","caller":"traceutil/trace.go:171","msg":"trace[385622804] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28584; }","duration":"141.216309ms","start":"2026-06-22T02:44:30.359081Z","end":"2026-06-22T02:44:30.500297Z","steps":["trace[385622804] 'agreement among raft nodes before linearized reading' (duration: 141.101087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.500345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.11437ms","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-06-22T02:44:30.500384Z","caller":"traceutil/trace.go:171","msg":"trace[926208968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28584; }","duration":"154.197842ms","start":"2026-06-22T02:44:30.346174Z","end":"2026-06-22T02:44:30.500372Z","steps":["trace[926208968] 'agreement among raft nodes before linearized reading' (duration: 154.09424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.500373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.443435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-22T02:44:30.500483Z","caller":"traceutil/trace.go:171","msg":"trace[1201046534] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28584; }","duration":"158.592669ms","start":"2026-06-22T02:44:30.341863Z","end":"2026-06-22T02:44:30.500456Z","steps":["trace[1201046534] 'agreement among raft nodes before linearized reading' (duration: 158.244921ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:30.500506Z","caller":"traceutil/trace.go:171","msg":"trace[1598228132] transaction","detail":"{read_only:false; response_revision:28584; number_of_response:1; }","duration":"118.143839ms","start":"2026-06-22T02:44:30.382352Z","end":"2026-06-22T02:44:30.500496Z","steps":["trace[1598228132] 'process raft request' (duration: 117.410273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.50019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.260993ms","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-06-22T02:44:30.500568Z","caller":"traceutil/trace.go:171","msg":"trace[510881821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28584; }","duration":"154.687203ms","start":"2026-06-22T02:44:30.34587Z","end":"2026-06-22T02:44:30.500557Z","steps":["trace[510881821] 'agreement among raft nodes before linearized reading' (duration: 154.139791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.500941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.395091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:30.500987Z","caller":"traceutil/trace.go:171","msg":"trace[1314172344] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:28584; }","duration":"220.501053ms","start":"2026-06-22T02:44:30.280471Z","end":"2026-06-22T02:44:30.500972Z","steps":["trace[1314172344] 'agreement among raft nodes before linearized reading' (duration: 220.411421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.903898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.138934ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:44:30.904259Z","caller":"traceutil/trace.go:171","msg":"trace[1318969094] linearizableReadLoop","detail":"{readStateIndex:29909; appliedIndex:29906; }","duration":"404.302069ms","start":"2026-06-22T02:44:30.499942Z","end":"2026-06-22T02:44:30.904244Z","steps":["trace[1318969094] 'read index received' (duration: 172.755736ms)","trace[1318969094] 'applied index is now lower than readState.Index' (duration: 231.545313ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:30.904327Z","caller":"traceutil/trace.go:171","msg":"trace[129653769] transaction","detail":"{read_only:false; response_revision:28585; number_of_response:1; }","duration":"469.912057ms","start":"2026-06-22T02:44:30.43435Z","end":"2026-06-22T02:44:30.904263Z","steps":["trace[129653769] 'process raft request' (duration: 238.343543ms)","trace[129653769] 'compare' (duration: 231.050013ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:30.904361Z","caller":"traceutil/trace.go:171","msg":"trace[2067851124] transaction","detail":"{read_only:false; response_revision:28587; number_of_response:1; }","duration":"429.798374ms","start":"2026-06-22T02:44:30.474519Z","end":"2026-06-22T02:44:30.904318Z","steps":["trace[2067851124] 'process raft request' (duration: 429.63458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.90451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.972947ms","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-06-22T02:44:30.904461Z","caller":"traceutil/trace.go:171","msg":"trace[154377734] transaction","detail":"{read_only:false; response_revision:28586; number_of_response:1; }","duration":"449.87133ms","start":"2026-06-22T02:44:30.454397Z","end":"2026-06-22T02:44:30.904269Z","steps":["trace[154377734] 'process raft request' (duration: 449.576693ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:30.904538Z","caller":"traceutil/trace.go:171","msg":"trace[1506976996] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28587; }","duration":"470.046929ms","start":"2026-06-22T02:44:30.434484Z","end":"2026-06-22T02:44:30.904531Z","steps":["trace[1506976996] 'agreement among raft nodes before linearized reading' (duration: 469.822815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:30.904559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.434457Z","time spent":"470.097511ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-22T02:44:30.904568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.43433Z","time spent":"470.10571ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:30.904571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.474491Z","time spent":"429.966197ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:30.904692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.454368Z","time spent":"450.196297ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:31.401875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.936726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:44:31.402219Z","caller":"traceutil/trace.go:171","msg":"trace[1920958554] linearizableReadLoop","detail":"{readStateIndex:29913; appliedIndex:29909; }","duration":"497.909745ms","start":"2026-06-22T02:44:30.904281Z","end":"2026-06-22T02:44:31.402191Z","steps":["trace[1920958554] 'read index received' (duration: 114.52984ms)","trace[1920958554] 'applied index is now lower than readState.Index' (duration: 383.378185ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:31.402511Z","caller":"traceutil/trace.go:171","msg":"trace[512192058] transaction","detail":"{read_only:false; response_revision:28588; number_of_response:1; }","duration":"897.128904ms","start":"2026-06-22T02:44:30.505335Z","end":"2026-06-22T02:44:31.402464Z","steps":["trace[512192058] 'process raft request' (duration: 513.505144ms)","trace[512192058] 'compare' (duration: 382.811132ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:31.402701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.505307Z","time spent":"897.299507ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:31.402667Z","caller":"traceutil/trace.go:171","msg":"trace[8943848] transaction","detail":"{read_only:false; response_revision:28590; number_of_response:1; }","duration":"895.883417ms","start":"2026-06-22T02:44:30.506735Z","end":"2026-06-22T02:44:31.402619Z","steps":["trace[8943848] 'process raft request' (duration: 895.312575ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:31.40275Z","caller":"traceutil/trace.go:171","msg":"trace[418248267] transaction","detail":"{read_only:false; response_revision:28591; number_of_response:1; }","duration":"631.140881ms","start":"2026-06-22T02:44:30.771585Z","end":"2026-06-22T02:44:31.402726Z","steps":["trace[418248267] 'process raft request' (duration: 630.513338ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:31.402739Z","caller":"traceutil/trace.go:171","msg":"trace[32784629] transaction","detail":"{read_only:false; response_revision:28592; number_of_response:1; }","duration":"492.272943ms","start":"2026-06-22T02:44:30.910417Z","end":"2026-06-22T02:44:31.40269Z","steps":["trace[32784629] 'process raft request' (duration: 491.718951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.402778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.175218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:31.402839Z","caller":"traceutil/trace.go:171","msg":"trace[1256341886] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:28592; }","duration":"893.26978ms","start":"2026-06-22T02:44:30.509556Z","end":"2026-06-22T02:44:31.402826Z","steps":["trace[1256341886] 'agreement among raft nodes before linearized reading' (duration: 893.129577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.402844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.771539Z","time spent":"631.255314ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:31.402858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.910401Z","time spent":"492.401125ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:31.402883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.509549Z","time spent":"893.326351ms","remote":"127.0.0.1:51978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-22T02:44:31.402878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.500439ms","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":"warn","ts":"2026-06-22T02:44:31.402926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.506725Z","time spent":"896.066331ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:31.402994Z","caller":"traceutil/trace.go:171","msg":"trace[1095728134] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28592; }","duration":"148.670112ms","start":"2026-06-22T02:44:31.254296Z","end":"2026-06-22T02:44:31.402966Z","steps":["trace[1095728134] 'agreement among raft nodes before linearized reading' (duration: 148.470808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.403232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.545264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-22T02:44:31.403273Z","caller":"traceutil/trace.go:171","msg":"trace[1386070491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28592; }","duration":"323.629086ms","start":"2026-06-22T02:44:31.079633Z","end":"2026-06-22T02:44:31.403262Z","steps":["trace[1386070491] 'agreement among raft nodes before linearized reading' (duration: 323.480633ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:31.402461Z","caller":"traceutil/trace.go:171","msg":"trace[1996835760] transaction","detail":"{read_only:false; response_revision:28589; number_of_response:1; }","duration":"896.151383ms","start":"2026-06-22T02:44:30.506276Z","end":"2026-06-22T02:44:31.402427Z","steps":["trace[1996835760] 'process raft request' (duration: 895.706423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.403318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:31.079608Z","time spent":"323.702597ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-22T02:44:31.403234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.976096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:31.403568Z","caller":"traceutil/trace.go:171","msg":"trace[1668366861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28592; }","duration":"234.426645ms","start":"2026-06-22T02:44:31.169103Z","end":"2026-06-22T02:44:31.40353Z","steps":["trace[1668366861] 'agreement among raft nodes before linearized reading' (duration: 233.628718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.403455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.799411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-22T02:44:31.403553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.546034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-22T02:44:31.403661Z","caller":"traceutil/trace.go:171","msg":"trace[367640638] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:28592; }","duration":"486.023096ms","start":"2026-06-22T02:44:30.917623Z","end":"2026-06-22T02:44:31.403646Z","steps":["trace[367640638] 'agreement among raft nodes before linearized reading' (duration: 485.75687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.403718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.917607Z","time spent":"486.093497ms","remote":"127.0.0.1:51938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":16,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-06-22T02:44:31.403724Z","caller":"traceutil/trace.go:171","msg":"trace[1301652629] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28592; }","duration":"898.722737ms","start":"2026-06-22T02:44:30.504942Z","end":"2026-06-22T02:44:31.403665Z","steps":["trace[1301652629] 'agreement among raft nodes before linearized reading' (duration: 897.42407ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.403811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.504925Z","time spent":"898.866192ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-22T02:44:31.403557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:30.506262Z","time spent":"897.074012ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:31.778357Z","caller":"traceutil/trace.go:171","msg":"trace[1566089472] transaction","detail":"{read_only:false; response_revision:28594; number_of_response:1; }","duration":"368.065611ms","start":"2026-06-22T02:44:31.410267Z","end":"2026-06-22T02:44:31.778333Z","steps":["trace[1566089472] 'process raft request' (duration: 367.991729ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:31.778468Z","caller":"traceutil/trace.go:171","msg":"trace[1731698194] transaction","detail":"{read_only:false; response_revision:28593; number_of_response:1; }","duration":"368.181563ms","start":"2026-06-22T02:44:31.410267Z","end":"2026-06-22T02:44:31.778449Z","steps":["trace[1731698194] 'process raft request' (duration: 367.717924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.778555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:31.410236Z","time spent":"368.273466ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:31.778503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:31.410253Z","time spent":"368.171854ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:31.809838Z","caller":"traceutil/trace.go:171","msg":"trace[1036854362] linearizableReadLoop","detail":"{readStateIndex:29917; appliedIndex:29914; }","duration":"394.961887ms","start":"2026-06-22T02:44:31.414839Z","end":"2026-06-22T02:44:31.809801Z","steps":["trace[1036854362] 'read index received' (duration: 363.236197ms)","trace[1036854362] 'applied index is now lower than readState.Index' (duration: 31.72437ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:31.809956Z","caller":"traceutil/trace.go:171","msg":"trace[1216093172] transaction","detail":"{read_only:false; response_revision:28595; number_of_response:1; }","duration":"395.972738ms","start":"2026-06-22T02:44:31.413921Z","end":"2026-06-22T02:44:31.809893Z","steps":["trace[1216093172] 'process raft request' (duration: 395.631941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.810099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.905361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:31.810199Z","caller":"traceutil/trace.go:171","msg":"trace[1068066805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28595; }","duration":"335.097425ms","start":"2026-06-22T02:44:31.475086Z","end":"2026-06-22T02:44:31.810183Z","steps":["trace[1068066805] 'agreement among raft nodes before linearized reading' (duration: 334.908531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.810257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:31.475057Z","time spent":"335.186807ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-22T02:44:31.810112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.247844ms","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-06-22T02:44:31.810349Z","caller":"traceutil/trace.go:171","msg":"trace[2074188193] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:28595; }","duration":"395.5255ms","start":"2026-06-22T02:44:31.41479Z","end":"2026-06-22T02:44:31.810316Z","steps":["trace[2074188193] 'agreement among raft nodes before linearized reading' (duration: 395.187062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:31.810429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:31.414777Z","time spent":"395.633332ms","remote":"127.0.0.1:52040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-22T02:44:31.810916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:31.413901Z","time spent":"396.159683ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:32.026908Z","caller":"traceutil/trace.go:171","msg":"trace[753035987] transaction","detail":"{read_only:false; response_revision:28596; number_of_response:1; }","duration":"211.386247ms","start":"2026-06-22T02:44:31.815489Z","end":"2026-06-22T02:44:32.026875Z","steps":["trace[753035987] 'process raft request' (duration: 185.234599ms)","trace[753035987] 'compare' (duration: 25.79053ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:32.027082Z","caller":"traceutil/trace.go:171","msg":"trace[764247281] linearizableReadLoop","detail":"{readStateIndex:29918; appliedIndex:29917; }","duration":"184.322227ms","start":"2026-06-22T02:44:31.842706Z","end":"2026-06-22T02:44:32.027029Z","steps":["trace[764247281] 'read index received' (duration: 158.055196ms)","trace[764247281] 'applied index is now lower than readState.Index' (duration: 26.261841ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:32.027486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.757917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-22T02:44:32.027552Z","caller":"traceutil/trace.go:171","msg":"trace[253508755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28597; }","duration":"184.89463ms","start":"2026-06-22T02:44:31.842642Z","end":"2026-06-22T02:44:32.027536Z","steps":["trace[253508755] 'agreement among raft nodes before linearized reading' (duration: 184.542142ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:32.325954Z","caller":"traceutil/trace.go:171","msg":"trace[1057034150] transaction","detail":"{read_only:false; response_revision:28598; number_of_response:1; }","duration":"293.636234ms","start":"2026-06-22T02:44:32.032276Z","end":"2026-06-22T02:44:32.325913Z","steps":["trace[1057034150] 'process raft request' (duration: 293.384939ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:32.339138Z","caller":"traceutil/trace.go:171","msg":"trace[969527948] linearizableReadLoop","detail":"{readStateIndex:29921; appliedIndex:29919; }","duration":"115.641774ms","start":"2026-06-22T02:44:32.223467Z","end":"2026-06-22T02:44:32.339109Z","steps":["trace[969527948] 'read index received' (duration: 102.182582ms)","trace[969527948] 'applied index is now lower than readState.Index' (duration: 13.457652ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:32.339212Z","caller":"traceutil/trace.go:171","msg":"trace[249611799] transaction","detail":"{read_only:false; response_revision:28599; number_of_response:1; }","duration":"261.609978ms","start":"2026-06-22T02:44:32.077562Z","end":"2026-06-22T02:44:32.339172Z","steps":["trace[249611799] 'process raft request' (duration: 261.365702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:32.339455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.888869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-06-22T02:44:32.339562Z","caller":"traceutil/trace.go:171","msg":"trace[1017318614] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:28599; }","duration":"116.138045ms","start":"2026-06-22T02:44:32.223383Z","end":"2026-06-22T02:44:32.339521Z","steps":["trace[1017318614] 'agreement among raft nodes before linearized reading' (duration: 115.836798ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:32.644176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.817778ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:32.644319Z","caller":"traceutil/trace.go:171","msg":"trace[1340998881] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28599; }","duration":"161.984651ms","start":"2026-06-22T02:44:32.482313Z","end":"2026-06-22T02:44:32.644298Z","steps":["trace[1340998881] 'range keys from in-memory index tree' (duration: 161.796436ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:32.644391Z","caller":"traceutil/trace.go:171","msg":"trace[54504609] transaction","detail":"{read_only:false; response_revision:28600; number_of_response:1; }","duration":"216.043226ms","start":"2026-06-22T02:44:32.428312Z","end":"2026-06-22T02:44:32.644355Z","steps":["trace[54504609] 'process raft request' (duration: 125.224642ms)","trace[54504609] 'compare' (duration: 90.536428ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:32.657837Z","caller":"traceutil/trace.go:171","msg":"trace[484239716] linearizableReadLoop","detail":"{readStateIndex:29923; appliedIndex:29921; }","duration":"183.343595ms","start":"2026-06-22T02:44:32.474478Z","end":"2026-06-22T02:44:32.657822Z","steps":["trace[484239716] 'read index received' (duration: 79.100589ms)","trace[484239716] 'applied index is now lower than readState.Index' (duration: 104.241456ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:32.657941Z","caller":"traceutil/trace.go:171","msg":"trace[308472121] transaction","detail":"{read_only:false; response_revision:28601; number_of_response:1; }","duration":"220.545395ms","start":"2026-06-22T02:44:32.437355Z","end":"2026-06-22T02:44:32.657901Z","steps":["trace[308472121] 'process raft request' (duration: 220.33724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:32.658557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.076782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:32.658599Z","caller":"traceutil/trace.go:171","msg":"trace[1501293873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28601; }","duration":"184.198754ms","start":"2026-06-22T02:44:32.474387Z","end":"2026-06-22T02:44:32.658586Z","steps":["trace[1501293873] 'agreement among raft nodes before linearized reading' (duration: 183.510829ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:33.081913Z","caller":"traceutil/trace.go:171","msg":"trace[1354811920] transaction","detail":"{read_only:false; response_revision:28602; number_of_response:1; }","duration":"167.89456ms","start":"2026-06-22T02:44:32.913976Z","end":"2026-06-22T02:44:33.081871Z","steps":["trace[1354811920] 'process raft request' (duration: 167.584542ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:33.143643Z","caller":"traceutil/trace.go:171","msg":"trace[1954625603] linearizableReadLoop","detail":"{readStateIndex:29925; appliedIndex:29923; }","duration":"225.012083ms","start":"2026-06-22T02:44:32.918587Z","end":"2026-06-22T02:44:33.143599Z","steps":["trace[1954625603] 'read index received' (duration: 162.916152ms)","trace[1954625603] 'applied index is now lower than readState.Index' (duration: 62.094101ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:33.143683Z","caller":"traceutil/trace.go:171","msg":"trace[164598703] transaction","detail":"{read_only:false; response_revision:28603; number_of_response:1; }","duration":"229.612811ms","start":"2026-06-22T02:44:32.914023Z","end":"2026-06-22T02:44:33.143636Z","steps":["trace[164598703] 'process raft request' (duration: 214.337769ms)","trace[164598703] 'compare' (duration: 14.959145ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:33.143891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.284238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"warn","ts":"2026-06-22T02:44:33.143912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.088663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:33.143975Z","caller":"traceutil/trace.go:171","msg":"trace[1266042168] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:28603; }","duration":"219.222866ms","start":"2026-06-22T02:44:32.924729Z","end":"2026-06-22T02:44:33.143952Z","steps":["trace[1266042168] 'agreement among raft nodes before linearized reading' (duration: 219.088443ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:33.143961Z","caller":"traceutil/trace.go:171","msg":"trace[215853345] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28603; }","duration":"225.39322ms","start":"2026-06-22T02:44:32.918551Z","end":"2026-06-22T02:44:33.143944Z","steps":["trace[215853345] 'agreement among raft nodes before linearized reading' (duration: 225.173645ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:33.694384Z","caller":"traceutil/trace.go:171","msg":"trace[623966991] transaction","detail":"{read_only:false; response_revision:28604; number_of_response:1; }","duration":"499.232584ms","start":"2026-06-22T02:44:33.195119Z","end":"2026-06-22T02:44:33.694352Z","steps":["trace[623966991] 'process raft request' (duration: 499.014759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:33.694554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.195067Z","time spent":"499.409787ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:34.194612Z","caller":"traceutil/trace.go:171","msg":"trace[880269574] transaction","detail":"{read_only:false; response_revision:28605; number_of_response:1; }","duration":"995.265408ms","start":"2026-06-22T02:44:33.199305Z","end":"2026-06-22T02:44:34.19457Z","steps":["trace[880269574] 'process raft request' (duration: 935.334634ms)","trace[880269574] 'compare' (duration: 59.439163ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:34.194831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.199266Z","time spent":"995.447882ms","remote":"127.0.0.1:37740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:34.194893Z","caller":"traceutil/trace.go:171","msg":"trace[545222148] linearizableReadLoop","detail":"{readStateIndex:29927; appliedIndex:29925; }","duration":"922.094397ms","start":"2026-06-22T02:44:33.272755Z","end":"2026-06-22T02:44:34.194849Z","steps":["trace[545222148] 'read index received' (duration: 421.35302ms)","trace[545222148] 'applied index is now lower than readState.Index' (duration: 500.740027ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:34.195077Z","caller":"traceutil/trace.go:171","msg":"trace[1739886144] transaction","detail":"{read_only:false; response_revision:28606; number_of_response:1; }","duration":"782.974952ms","start":"2026-06-22T02:44:33.412079Z","end":"2026-06-22T02:44:34.195054Z","steps":["trace[1739886144] 'process raft request' (duration: 782.3794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.195206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.451154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"warn","ts":"2026-06-22T02:44:34.195244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.412061Z","time spent":"783.066475ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:34.195278Z","caller":"traceutil/trace.go:171","msg":"trace[255678738] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:28606; }","duration":"922.559627ms","start":"2026-06-22T02:44:33.272695Z","end":"2026-06-22T02:44:34.195255Z","steps":["trace[255678738] 'agreement among raft nodes before linearized reading' (duration: 922.361192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.195334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.272672Z","time spent":"922.649838ms","remote":"127.0.0.1:51952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-06-22T02:44:34.456945Z","caller":"traceutil/trace.go:171","msg":"trace[403484015] linearizableReadLoop","detail":"{readStateIndex:29930; appliedIndex:29928; }","duration":"261.933674ms","start":"2026-06-22T02:44:34.194992Z","end":"2026-06-22T02:44:34.456925Z","steps":["trace[403484015] 'read index received' (duration: 261.680799ms)","trace[403484015] 'applied index is now lower than readState.Index' (duration: 251.815µs)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:34.457194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044994198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-06-22T02:44:34.457172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.473628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-22T02:44:34.457225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.580135ms","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-06-22T02:44:34.457283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.661718ms","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-06-22T02:44:34.457282Z","caller":"traceutil/trace.go:171","msg":"trace[2118513857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28607; }","duration":"982.664252ms","start":"2026-06-22T02:44:33.474598Z","end":"2026-06-22T02:44:34.457262Z","steps":["trace[2118513857] 'agreement among raft nodes before linearized reading' (duration: 982.428567ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.457282Z","caller":"traceutil/trace.go:171","msg":"trace[1551364594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28607; }","duration":"674.679517ms","start":"2026-06-22T02:44:33.782587Z","end":"2026-06-22T02:44:34.457266Z","steps":["trace[1551364594] 'agreement among raft nodes before linearized reading' (duration: 674.495473ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.45733Z","caller":"traceutil/trace.go:171","msg":"trace[1642646043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28607; }","duration":"382.741771ms","start":"2026-06-22T02:44:34.074574Z","end":"2026-06-22T02:44:34.457316Z","steps":["trace[1642646043] 'agreement among raft nodes before linearized reading' (duration: 382.667659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048663729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-22T02:44:34.457342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.474569Z","time spent":"982.763846ms","remote":"127.0.0.1:51788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-22T02:44:34.457342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.782556Z","time spent":"674.77755ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-22T02:44:34.457364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.074556Z","time spent":"382.800942ms","remote":"127.0.0.1:35690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-22T02:44:34.457433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.331257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8f5106c7-ad9f-4d10-815b-0f121143649b\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-06-22T02:44:34.457461Z","caller":"traceutil/trace.go:171","msg":"trace[1970317310] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8f5106c7-ad9f-4d10-815b-0f121143649b; range_end:; response_count:1; response_revision:28607; }","duration":"258.402238ms","start":"2026-06-22T02:44:34.199049Z","end":"2026-06-22T02:44:34.457451Z","steps":["trace[1970317310] 'agreement among raft nodes before linearized reading' (duration: 258.335687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045125422s","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-06-22T02:44:34.457524Z","caller":"traceutil/trace.go:171","msg":"trace[206069018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28607; }","duration":"1.045408628s","start":"2026-06-22T02:44:33.412098Z","end":"2026-06-22T02:44:34.457507Z","steps":["trace[206069018] 'agreement among raft nodes before linearized reading' (duration: 1.045146512s)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.457366Z","caller":"traceutil/trace.go:171","msg":"trace[1777158465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28607; }","duration":"1.04872267s","start":"2026-06-22T02:44:33.408637Z","end":"2026-06-22T02:44:34.45736Z","steps":["trace[1777158465] 'agreement among raft nodes before linearized reading' (duration: 1.048673249s)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.412063Z","time spent":"1.04549504s","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-22T02:44:34.457593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.408615Z","time spent":"1.048968975s","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-22T02:44:34.457605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.721947ms","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-06-22T02:44:34.457643Z","caller":"traceutil/trace.go:171","msg":"trace[520735505] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:28607; }","duration":"828.797488ms","start":"2026-06-22T02:44:33.628831Z","end":"2026-06-22T02:44:34.457629Z","steps":["trace[520735505] 'agreement among raft nodes before linearized reading' (duration: 828.734187ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.457236Z","caller":"traceutil/trace.go:171","msg":"trace[526819904] transaction","detail":"{read_only:false; response_revision:28607; number_of_response:1; }","duration":"423.518427ms","start":"2026-06-22T02:44:34.033662Z","end":"2026-06-22T02:44:34.457181Z","steps":["trace[526819904] 'process raft request' (duration: 423.16697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.628809Z","time spent":"828.87027ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-06-22T02:44:34.457715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.516208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-22T02:44:34.457736Z","caller":"traceutil/trace.go:171","msg":"trace[1473520680] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:28607; }","duration":"124.550918ms","start":"2026-06-22T02:44:34.333178Z","end":"2026-06-22T02:44:34.457729Z","steps":["trace[1473520680] 'agreement among raft nodes before linearized reading' (duration: 124.481457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.246146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-22T02:44:34.45783Z","caller":"traceutil/trace.go:171","msg":"trace[2064734016] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28607; }","duration":"426.298717ms","start":"2026-06-22T02:44:34.031519Z","end":"2026-06-22T02:44:34.457818Z","steps":["trace[2064734016] 'agreement among raft nodes before linearized reading' (duration: 426.148704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.031503Z","time spent":"426.357619ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-06-22T02:44:34.4579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.460422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-22T02:44:34.45793Z","caller":"traceutil/trace.go:171","msg":"trace[2057743789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28607; }","duration":"127.525573ms","start":"2026-06-22T02:44:34.330398Z","end":"2026-06-22T02:44:34.457924Z","steps":["trace[2057743789] 'agreement among raft nodes before linearized reading' (duration: 127.449152ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.457228Z","caller":"traceutil/trace.go:171","msg":"trace[1346732948] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28607; }","duration":"1.045106411s","start":"2026-06-22T02:44:33.412111Z","end":"2026-06-22T02:44:34.457217Z","steps":["trace[1346732948] 'agreement among raft nodes before linearized reading' (duration: 1.044927957s)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.41208Z","time spent":"1.045897418s","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-06-22T02:44:34.457786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.033646Z","time spent":"424.06767ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:34.458001Z","caller":"traceutil/trace.go:171","msg":"trace[1970968152] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28606; }","duration":"754.235477ms","start":"2026-06-22T02:44:33.702703Z","end":"2026-06-22T02:44:34.456938Z","steps":["trace[1970968152] 'process raft request' (duration: 754.047633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.457211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.612295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-22T02:44:34.458152Z","caller":"traceutil/trace.go:171","msg":"trace[1424044866] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28607; }","duration":"640.599357ms","start":"2026-06-22T02:44:33.817537Z","end":"2026-06-22T02:44:34.458136Z","steps":["trace[1424044866] 'agreement among raft nodes before linearized reading' (duration: 639.446781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.458187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.817507Z","time spent":"640.669409ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-22T02:44:34.458271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:33.702681Z","time spent":"755.473514ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-22T02:44:34.807592Z","caller":"traceutil/trace.go:171","msg":"trace[470796325] transaction","detail":"{read_only:false; response_revision:28608; number_of_response:1; }","duration":"343.986778ms","start":"2026-06-22T02:44:34.463551Z","end":"2026-06-22T02:44:34.807538Z","steps":["trace[470796325] 'process raft request' (duration: 322.898929ms)","trace[470796325] 'compare' (duration: 20.744201ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:34.807817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.463535Z","time spent":"344.159801ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:34.80787Z","caller":"traceutil/trace.go:171","msg":"trace[1228597382] transaction","detail":"{read_only:false; response_revision:28609; number_of_response:1; }","duration":"344.257684ms","start":"2026-06-22T02:44:34.463601Z","end":"2026-06-22T02:44:34.807858Z","steps":["trace[1228597382] 'process raft request' (duration: 343.753982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.80804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.463584Z","time spent":"344.345556ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:34.807794Z","caller":"traceutil/trace.go:171","msg":"trace[75946313] transaction","detail":"{read_only:false; response_revision:28616; number_of_response:1; }","duration":"143.993841ms","start":"2026-06-22T02:44:34.663773Z","end":"2026-06-22T02:44:34.807767Z","steps":["trace[75946313] 'process raft request' (duration: 143.94659ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.808286Z","caller":"traceutil/trace.go:171","msg":"trace[1673419381] linearizableReadLoop","detail":"{readStateIndex:29937; appliedIndex:29931; }","duration":"251.540019ms","start":"2026-06-22T02:44:34.556731Z","end":"2026-06-22T02:44:34.808271Z","steps":["trace[1673419381] 'read index received' (duration: 229.755245ms)","trace[1673419381] 'applied index is now lower than readState.Index' (duration: 21.783384ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:34.808288Z","caller":"traceutil/trace.go:171","msg":"trace[1646266921] transaction","detail":"{read_only:false; response_revision:28613; number_of_response:1; }","duration":"334.958112ms","start":"2026-06-22T02:44:34.473275Z","end":"2026-06-22T02:44:34.808233Z","steps":["trace[1646266921] 'process raft request' (duration: 334.270347ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.80835Z","caller":"traceutil/trace.go:171","msg":"trace[1343029600] transaction","detail":"{read_only:false; response_revision:28615; number_of_response:1; }","duration":"245.60351ms","start":"2026-06-22T02:44:34.5627Z","end":"2026-06-22T02:44:34.808304Z","steps":["trace[1343029600] 'process raft request' (duration: 244.966496ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.808366Z","caller":"traceutil/trace.go:171","msg":"trace[1971352052] transaction","detail":"{read_only:false; response_revision:28611; number_of_response:1; }","duration":"341.530235ms","start":"2026-06-22T02:44:34.466799Z","end":"2026-06-22T02:44:34.808329Z","steps":["trace[1971352052] 'process raft request' (duration: 340.673787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.808562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.473252Z","time spent":"335.153787ms","remote":"127.0.0.1:52254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:34.808581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.466791Z","time spent":"341.698989ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:34.80861Z","caller":"traceutil/trace.go:171","msg":"trace[31564365] transaction","detail":"{read_only:false; response_revision:28614; number_of_response:1; }","duration":"250.773823ms","start":"2026-06-22T02:44:34.557811Z","end":"2026-06-22T02:44:34.808585Z","steps":["trace[31564365] 'process raft request' (duration: 249.810212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.808688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.703765ms","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-06-22T02:44:34.80837Z","caller":"traceutil/trace.go:171","msg":"trace[273997538] transaction","detail":"{read_only:false; response_revision:28610; number_of_response:1; }","duration":"343.497058ms","start":"2026-06-22T02:44:34.464846Z","end":"2026-06-22T02:44:34.808343Z","steps":["trace[273997538] 'process raft request' (duration: 342.594288ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:34.808763Z","caller":"traceutil/trace.go:171","msg":"trace[1040376184] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28616; }","duration":"345.794427ms","start":"2026-06-22T02:44:34.462944Z","end":"2026-06-22T02:44:34.808739Z","steps":["trace[1040376184] 'agreement among raft nodes before linearized reading' (duration: 345.441649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.808775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.464835Z","time spent":"343.911757ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:34.808812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.46293Z","time spent":"345.872778ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-22T02:44:34.80888Z","caller":"traceutil/trace.go:171","msg":"trace[117536916] transaction","detail":"{read_only:false; response_revision:28612; number_of_response:1; }","duration":"340.79659ms","start":"2026-06-22T02:44:34.468068Z","end":"2026-06-22T02:44:34.808864Z","steps":["trace[117536916] 'process raft request' (duration: 339.43349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:34.808947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:34.468058Z","time spent":"340.854841ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:34.808941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.178423ms","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-06-22T02:44:34.808996Z","caller":"traceutil/trace.go:171","msg":"trace[565026645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28616; }","duration":"160.252364ms","start":"2026-06-22T02:44:34.648731Z","end":"2026-06-22T02:44:34.808983Z","steps":["trace[565026645] 'agreement among raft nodes before linearized reading' (duration: 160.030369ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:35.100423Z","caller":"traceutil/trace.go:171","msg":"trace[1528082879] linearizableReadLoop","detail":"{readStateIndex:29943; appliedIndex:29940; }","duration":"266.206807ms","start":"2026-06-22T02:44:34.834179Z","end":"2026-06-22T02:44:35.100385Z","steps":["trace[1528082879] 'read index received' (duration: 46.4361ms)","trace[1528082879] 'applied index is now lower than readState.Index' (duration: 219.769197ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:35.100499Z","caller":"traceutil/trace.go:171","msg":"trace[1117930928] transaction","detail":"{read_only:false; response_revision:28618; number_of_response:1; }","duration":"282.938411ms","start":"2026-06-22T02:44:34.817518Z","end":"2026-06-22T02:44:35.100457Z","steps":["trace[1117930928] 'process raft request' (duration: 282.570853ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:35.100541Z","caller":"traceutil/trace.go:171","msg":"trace[1865871495] transaction","detail":"{read_only:false; response_revision:28619; number_of_response:1; }","duration":"282.794088ms","start":"2026-06-22T02:44:34.817707Z","end":"2026-06-22T02:44:35.100501Z","steps":["trace[1865871495] 'process raft request' (duration: 282.600444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:35.100709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.500313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-06-22T02:44:35.100779Z","caller":"traceutil/trace.go:171","msg":"trace[222776291] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28619; }","duration":"266.649686ms","start":"2026-06-22T02:44:34.834116Z","end":"2026-06-22T02:44:35.100766Z","steps":["trace[222776291] 'agreement among raft nodes before linearized reading' (duration: 266.411281ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:35.291051Z","caller":"traceutil/trace.go:171","msg":"trace[1008486426] linearizableReadLoop","detail":"{readStateIndex:29946; appliedIndex:29944; }","duration":"140.472254ms","start":"2026-06-22T02:44:35.150556Z","end":"2026-06-22T02:44:35.291028Z","steps":["trace[1008486426] 'read index received' (duration: 128.387431ms)","trace[1008486426] 'applied index is now lower than readState.Index' (duration: 12.083623ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:35.291321Z","caller":"traceutil/trace.go:171","msg":"trace[1504347233] transaction","detail":"{read_only:false; response_revision:28621; number_of_response:1; }","duration":"147.324232ms","start":"2026-06-22T02:44:35.14397Z","end":"2026-06-22T02:44:35.291294Z","steps":["trace[1504347233] 'process raft request' (duration: 134.952804ms)","trace[1504347233] 'compare' (duration: 11.318036ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:35.291343Z","caller":"traceutil/trace.go:171","msg":"trace[1439808431] transaction","detail":"{read_only:false; response_revision:28622; number_of_response:1; }","duration":"142.563169ms","start":"2026-06-22T02:44:35.148749Z","end":"2026-06-22T02:44:35.291312Z","steps":["trace[1439808431] 'process raft request' (duration: 142.185281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:35.291777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.211809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-06-22T02:44:35.291834Z","caller":"traceutil/trace.go:171","msg":"trace[2009980153] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:28622; }","duration":"141.322232ms","start":"2026-06-22T02:44:35.150498Z","end":"2026-06-22T02:44:35.29182Z","steps":["trace[2009980153] 'agreement among raft nodes before linearized reading' (duration: 140.615327ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:36.363933Z","caller":"traceutil/trace.go:171","msg":"trace[431419394] transaction","detail":"{read_only:false; response_revision:28624; number_of_response:1; }","duration":"191.293909ms","start":"2026-06-22T02:44:36.172614Z","end":"2026-06-22T02:44:36.363908Z","steps":["trace[431419394] 'process raft request' (duration: 191.132935ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:36.406276Z","caller":"traceutil/trace.go:171","msg":"trace[1531705816] linearizableReadLoop","detail":"{readStateIndex:29949; appliedIndex:29947; }","duration":"159.638771ms","start":"2026-06-22T02:44:36.246594Z","end":"2026-06-22T02:44:36.406233Z","steps":["trace[1531705816] 'read index received' (duration: 117.167347ms)","trace[1531705816] 'applied index is now lower than readState.Index' (duration: 42.468783ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:36.406421Z","caller":"traceutil/trace.go:171","msg":"trace[1388160114] transaction","detail":"{read_only:false; response_revision:28625; number_of_response:1; }","duration":"204.286891ms","start":"2026-06-22T02:44:36.202103Z","end":"2026-06-22T02:44:36.40639Z","steps":["trace[1388160114] 'process raft request' (duration: 202.120914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:36.406521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.892396ms","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-06-22T02:44:36.406572Z","caller":"traceutil/trace.go:171","msg":"trace[113497218] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:28625; }","duration":"159.983647ms","start":"2026-06-22T02:44:36.246574Z","end":"2026-06-22T02:44:36.406557Z","steps":["trace[113497218] 'agreement among raft nodes before linearized reading' (duration: 159.835144ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:36.598728Z","caller":"traceutil/trace.go:171","msg":"trace[491106132] transaction","detail":"{read_only:false; response_revision:28626; number_of_response:1; }","duration":"134.717028ms","start":"2026-06-22T02:44:36.463952Z","end":"2026-06-22T02:44:36.598669Z","steps":["trace[491106132] 'process raft request' (duration: 110.826359ms)","trace[491106132] 'compare' (duration: 23.640384ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:36.598669Z","caller":"traceutil/trace.go:171","msg":"trace[15840235] linearizableReadLoop","detail":"{readStateIndex:29950; appliedIndex:29949; }","duration":"124.817013ms","start":"2026-06-22T02:44:36.47381Z","end":"2026-06-22T02:44:36.598627Z","steps":["trace[15840235] 'read index received' (duration: 101.013866ms)","trace[15840235] 'applied index is now lower than readState.Index' (duration: 23.802007ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:36.598949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.127021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:36.599028Z","caller":"traceutil/trace.go:171","msg":"trace[276028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28626; }","duration":"125.265284ms","start":"2026-06-22T02:44:36.473743Z","end":"2026-06-22T02:44:36.599008Z","steps":["trace[276028] 'agreement among raft nodes before linearized reading' (duration: 125.123911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:41.483977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.947827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:44:41.484074Z","caller":"traceutil/trace.go:171","msg":"trace[1128805361] linearizableReadLoop","detail":"{readStateIndex:29996; appliedIndex:29995; }","duration":"328.804179ms","start":"2026-06-22T02:44:41.155256Z","end":"2026-06-22T02:44:41.48406Z","steps":["trace[1128805361] 'read index received' (duration: 111.796481ms)","trace[1128805361] 'applied index is now lower than readState.Index' (duration: 217.006128ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:41.484129Z","caller":"traceutil/trace.go:171","msg":"trace[1107730697] transaction","detail":"{read_only:false; response_revision:28670; number_of_response:1; }","duration":"478.708897ms","start":"2026-06-22T02:44:41.005412Z","end":"2026-06-22T02:44:41.484121Z","steps":["trace[1107730697] 'process raft request' (duration: 261.543216ms)","trace[1107730697] 'compare' (duration: 216.754733ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:41.484243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:41.005377Z","time spent":"478.839881ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:41.484297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.036154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-22T02:44:41.484399Z","caller":"traceutil/trace.go:171","msg":"trace[1322312891] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28670; }","duration":"329.193167ms","start":"2026-06-22T02:44:41.155179Z","end":"2026-06-22T02:44:41.484372Z","steps":["trace[1322312891] 'agreement among raft nodes before linearized reading' (duration: 328.943251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:41.484461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:41.15514Z","time spent":"329.305829ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-06-22T02:44:41.491526Z","caller":"traceutil/trace.go:171","msg":"trace[1259297711] transaction","detail":"{read_only:false; response_revision:28671; number_of_response:1; }","duration":"333.315425ms","start":"2026-06-22T02:44:41.158175Z","end":"2026-06-22T02:44:41.491491Z","steps":["trace[1259297711] 'process raft request' (duration: 333.144371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:41.491718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:41.158156Z","time spent":"333.484161ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:41.527731Z","caller":"traceutil/trace.go:171","msg":"trace[993421207] transaction","detail":"{read_only:false; response_revision:28672; number_of_response:1; }","duration":"212.923179ms","start":"2026-06-22T02:44:41.314769Z","end":"2026-06-22T02:44:41.527692Z","steps":["trace[993421207] 'process raft request' (duration: 212.528001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:41.527977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.313055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-22T02:44:41.528062Z","caller":"traceutil/trace.go:171","msg":"trace[141213854] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28672; }","duration":"177.449899ms","start":"2026-06-22T02:44:41.350591Z","end":"2026-06-22T02:44:41.528041Z","steps":["trace[141213854] 'agreement among raft nodes before linearized reading' (duration: 177.07851ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:42.108409Z","caller":"traceutil/trace.go:171","msg":"trace[895854039] transaction","detail":"{read_only:false; response_revision:28673; number_of_response:1; }","duration":"575.42478ms","start":"2026-06-22T02:44:41.53296Z","end":"2026-06-22T02:44:42.108385Z","steps":["trace[895854039] 'process raft request' (duration: 575.219936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:42.108553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:41.532948Z","time spent":"575.526032ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:42.333188Z","caller":"traceutil/trace.go:171","msg":"trace[263807826] linearizableReadLoop","detail":"{readStateIndex:30000; appliedIndex:29998; }","duration":"506.372259ms","start":"2026-06-22T02:44:41.826785Z","end":"2026-06-22T02:44:42.333157Z","steps":["trace[263807826] 'read index received' (duration: 281.49956ms)","trace[263807826] 'applied index is now lower than readState.Index' (duration: 224.870879ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:42.333212Z","caller":"traceutil/trace.go:171","msg":"trace[1037582220] transaction","detail":"{read_only:false; response_revision:28674; number_of_response:1; }","duration":"797.066089ms","start":"2026-06-22T02:44:41.536101Z","end":"2026-06-22T02:44:42.333168Z","steps":["trace[1037582220] 'process raft request' (duration: 796.19356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:42.333389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.587294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-22T02:44:42.333413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:41.536073Z","time spent":"797.224853ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:44:42.33344Z","caller":"traceutil/trace.go:171","msg":"trace[28643593] 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:28674; }","duration":"506.705856ms","start":"2026-06-22T02:44:41.826718Z","end":"2026-06-22T02:44:42.333424Z","steps":["trace[28643593] 'agreement among raft nodes before linearized reading' (duration: 506.596024ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:42.333488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:41.826675Z","time spent":"506.802268ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-06-22T02:44:42.334105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.528946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"info","ts":"2026-06-22T02:44:42.334453Z","caller":"traceutil/trace.go:171","msg":"trace[1078931049] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28674; }","duration":"221.674919ms","start":"2026-06-22T02:44:42.112513Z","end":"2026-06-22T02:44:42.334188Z","steps":["trace[1078931049] 'agreement among raft nodes before linearized reading' (duration: 221.377592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:42.589163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.7298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-06-22T02:44:42.589039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.445645ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:42.589424Z","caller":"traceutil/trace.go:171","msg":"trace[1303479489] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28674; }","duration":"107.864374ms","start":"2026-06-22T02:44:42.481544Z","end":"2026-06-22T02:44:42.589408Z","steps":["trace[1303479489] 'range keys from in-memory index tree' (duration: 107.429715ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:42.589577Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-22T02:44:42.589756Z","caller":"traceutil/trace.go:171","msg":"trace[340005748] transaction","detail":"{read_only:false; response_revision:28675; number_of_response:1; }","duration":"248.959502ms","start":"2026-06-22T02:44:42.340783Z","end":"2026-06-22T02:44:42.589743Z","steps":["trace[340005748] 'process raft request' (duration: 101.571138ms)","trace[340005748] 'compare' (duration: 146.620787ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:42.590278Z","caller":"traceutil/trace.go:171","msg":"trace[1855115078] transaction","detail":"{read_only:false; response_revision:28676; number_of_response:1; }","duration":"247.401299ms","start":"2026-06-22T02:44:42.342828Z","end":"2026-06-22T02:44:42.590229Z","steps":["trace[1855115078] 'process raft request' (duration: 246.426227ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:42.590828Z","caller":"traceutil/trace.go:171","msg":"trace[661451611] transaction","detail":"{read_only:false; response_revision:28677; number_of_response:1; }","duration":"165.390876ms","start":"2026-06-22T02:44:42.425414Z","end":"2026-06-22T02:44:42.590805Z","steps":["trace[661451611] 'process raft request' (duration: 164.101557ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:42.591285Z","caller":"traceutil/trace.go:171","msg":"trace[225914927] linearizableReadLoop","detail":"{readStateIndex:30001; appliedIndex:30000; }","duration":"249.581676ms","start":"2026-06-22T02:44:42.341692Z","end":"2026-06-22T02:44:42.591273Z","steps":["trace[225914927] 'read index received' (duration: 100.680659ms)","trace[225914927] 'applied index is now lower than readState.Index' (duration: 148.899517ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:44:42.591903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.681093ms","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:6"} {"level":"info","ts":"2026-06-22T02:44:42.595332Z","caller":"traceutil/trace.go:171","msg":"trace[1387514575] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:28677; }","duration":"245.16864ms","start":"2026-06-22T02:44:42.350141Z","end":"2026-06-22T02:44:42.595309Z","steps":["trace[1387514575] 'agreement among raft nodes before linearized reading' (duration: 241.644183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:42.59146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.76222ms","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-06-22T02:44:42.592319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.39147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:42.596173Z","caller":"traceutil/trace.go:171","msg":"trace[1913755914] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:28677; }","duration":"254.464372ms","start":"2026-06-22T02:44:42.341683Z","end":"2026-06-22T02:44:42.596148Z","steps":["trace[1913755914] 'agreement among raft nodes before linearized reading' (duration: 249.671938ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:42.596708Z","caller":"traceutil/trace.go:171","msg":"trace[184069476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28677; }","duration":"119.981919ms","start":"2026-06-22T02:44:42.475858Z","end":"2026-06-22T02:44:42.59584Z","steps":["trace[184069476] 'agreement among raft nodes before linearized reading' (duration: 116.427971ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:42.600974Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-22T02:44:42.60113Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-22T02:44:49.636675Z","caller":"traceutil/trace.go:171","msg":"trace[1686550735] transaction","detail":"{read_only:false; response_revision:28795; number_of_response:1; }","duration":"109.00183ms","start":"2026-06-22T02:44:49.527617Z","end":"2026-06-22T02:44:49.636619Z","steps":["trace[1686550735] 'process raft request' (duration: 108.699103ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:49.721288Z","caller":"traceutil/trace.go:171","msg":"trace[193875298] transaction","detail":"{read_only:false; response_revision:28796; number_of_response:1; }","duration":"161.207854ms","start":"2026-06-22T02:44:49.560007Z","end":"2026-06-22T02:44:49.721215Z","steps":["trace[193875298] 'process raft request' (duration: 160.921728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:49.874742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.60354ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-06-22T02:44:49.875213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.71801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3dd426f-1d70-43af-8c43-ad2862e26dca\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-22T02:44:49.875325Z","caller":"traceutil/trace.go:171","msg":"trace[776412493] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e3dd426f-1d70-43af-8c43-ad2862e26dca; range_end:; response_count:1; response_revision:28796; }","duration":"307.873963ms","start":"2026-06-22T02:44:49.567426Z","end":"2026-06-22T02:44:49.8753Z","steps":["trace[776412493] 'agreement among raft nodes before linearized reading' (duration: 307.585257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:49.875376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:49.567387Z","time spent":"307.979076ms","remote":"127.0.0.1:51974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3665,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3dd426f-1d70-43af-8c43-ad2862e26dca\" "} {"level":"info","ts":"2026-06-22T02:44:49.87492Z","caller":"traceutil/trace.go:171","msg":"trace[894609406] linearizableReadLoop","detail":"{readStateIndex:30125; appliedIndex:30124; }","duration":"307.412404ms","start":"2026-06-22T02:44:49.567469Z","end":"2026-06-22T02:44:49.874881Z","steps":["trace[894609406] 'read index received' (duration: 69.107853ms)","trace[894609406] 'applied index is now lower than readState.Index' (duration: 238.30214ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:50.774564Z","caller":"traceutil/trace.go:171","msg":"trace[1402527188] linearizableReadLoop","detail":"{readStateIndex:30128; appliedIndex:30127; }","duration":"146.114946ms","start":"2026-06-22T02:44:50.628421Z","end":"2026-06-22T02:44:50.774536Z","steps":["trace[1402527188] 'read index received' (duration: 146.001334ms)","trace[1402527188] 'applied index is now lower than readState.Index' (duration: 112.082µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:50.774685Z","caller":"traceutil/trace.go:171","msg":"trace[468069333] transaction","detail":"{read_only:false; response_revision:28798; number_of_response:1; }","duration":"146.366321ms","start":"2026-06-22T02:44:50.628268Z","end":"2026-06-22T02:44:50.774634Z","steps":["trace[468069333] 'process raft request' (duration: 146.108076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:50.774896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.450783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9726"} {"level":"info","ts":"2026-06-22T02:44:50.774975Z","caller":"traceutil/trace.go:171","msg":"trace[1946344196] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:28798; }","duration":"146.564796ms","start":"2026-06-22T02:44:50.62839Z","end":"2026-06-22T02:44:50.774955Z","steps":["trace[1946344196] 'agreement among raft nodes before linearized reading' (duration: 146.27974ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:50.981579Z","caller":"traceutil/trace.go:171","msg":"trace[1711923433] transaction","detail":"{read_only:false; response_revision:28799; number_of_response:1; }","duration":"351.234005ms","start":"2026-06-22T02:44:50.630301Z","end":"2026-06-22T02:44:50.981535Z","steps":["trace[1711923433] 'process raft request' (duration: 350.791056ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:50.981739Z","caller":"traceutil/trace.go:171","msg":"trace[1364843797] transaction","detail":"{read_only:false; response_revision:28801; number_of_response:1; }","duration":"287.603482ms","start":"2026-06-22T02:44:50.694102Z","end":"2026-06-22T02:44:50.981705Z","steps":["trace[1364843797] 'process raft request' (duration: 287.49775ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:50.981874Z","caller":"traceutil/trace.go:171","msg":"trace[1265609886] linearizableReadLoop","detail":"{readStateIndex:30132; appliedIndex:30128; }","duration":"207.227875ms","start":"2026-06-22T02:44:50.774633Z","end":"2026-06-22T02:44:50.98186Z","steps":["trace[1265609886] 'read index received' (duration: 206.479849ms)","trace[1265609886] 'applied index is now lower than readState.Index' (duration: 745.896µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:50.981857Z","caller":"traceutil/trace.go:171","msg":"trace[737564996] transaction","detail":"{read_only:false; response_revision:28800; number_of_response:1; }","duration":"329.322709ms","start":"2026-06-22T02:44:50.652507Z","end":"2026-06-22T02:44:50.981829Z","steps":["trace[737564996] 'process raft request' (duration: 328.954671ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:50.981986Z","caller":"traceutil/trace.go:171","msg":"trace[1001720993] transaction","detail":"{read_only:false; response_revision:28802; number_of_response:1; }","duration":"238.74372ms","start":"2026-06-22T02:44:50.743234Z","end":"2026-06-22T02:44:50.981977Z","steps":["trace[1001720993] 'process raft request' (duration: 238.426383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:50.981772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:50.630267Z","time spent":"351.404939ms","remote":"127.0.0.1:51888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:50.982233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.186515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:50.982276Z","caller":"traceutil/trace.go:171","msg":"trace[1654587253] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:28802; }","duration":"219.267297ms","start":"2026-06-22T02:44:50.762995Z","end":"2026-06-22T02:44:50.982263Z","steps":["trace[1654587253] 'agreement among raft nodes before linearized reading' (duration: 219.116174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:50.982347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.144335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18bb4721e9dac896\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-06-22T02:44:50.982363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.674209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:50.982404Z","caller":"traceutil/trace.go:171","msg":"trace[465425908] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18bb4721e9dac896; range_end:; response_count:1; response_revision:28802; }","duration":"277.237307ms","start":"2026-06-22T02:44:50.705152Z","end":"2026-06-22T02:44:50.98239Z","steps":["trace[465425908] 'agreement among raft nodes before linearized reading' (duration: 277.031742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:50.982395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.315642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-22T02:44:50.982446Z","caller":"traceutil/trace.go:171","msg":"trace[1683554664] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:28802; }","duration":"256.797282ms","start":"2026-06-22T02:44:50.725626Z","end":"2026-06-22T02:44:50.982424Z","steps":["trace[1683554664] 'agreement among raft nodes before linearized reading' (duration: 256.645559ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:50.98246Z","caller":"traceutil/trace.go:171","msg":"trace[1323774417] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28802; }","duration":"308.435866ms","start":"2026-06-22T02:44:50.674005Z","end":"2026-06-22T02:44:50.98244Z","steps":["trace[1323774417] 'agreement among raft nodes before linearized reading' (duration: 308.247772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:50.982505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:50.673981Z","time spent":"308.514407ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-22T02:44:50.982034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:50.652478Z","time spent":"329.460852ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:44:50.982532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.181406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-06-22T02:44:50.982888Z","caller":"traceutil/trace.go:171","msg":"trace[1826082770] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28802; }","duration":"306.430763ms","start":"2026-06-22T02:44:50.676292Z","end":"2026-06-22T02:44:50.982723Z","steps":["trace[1826082770] 'agreement among raft nodes before linearized reading' (duration: 305.948951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:50.982944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:44:50.676269Z","time spent":"306.662247ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-06-22T02:44:51.056738Z","caller":"traceutil/trace.go:171","msg":"trace[455646020] transaction","detail":"{read_only:false; response_revision:28803; number_of_response:1; }","duration":"188.627451ms","start":"2026-06-22T02:44:50.868081Z","end":"2026-06-22T02:44:51.056709Z","steps":["trace[455646020] 'process raft request' (duration: 188.474788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:51.057303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.612076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-22T02:44:51.057398Z","caller":"traceutil/trace.go:171","msg":"trace[2128752732] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28803; }","duration":"151.735058ms","start":"2026-06-22T02:44:50.905642Z","end":"2026-06-22T02:44:51.057377Z","steps":["trace[2128752732] 'agreement among raft nodes before linearized reading' (duration: 151.557574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:51.057453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.094262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-22T02:44:51.057489Z","caller":"traceutil/trace.go:171","msg":"trace[2086700739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28803; }","duration":"161.186373ms","start":"2026-06-22T02:44:50.896292Z","end":"2026-06-22T02:44:51.057479Z","steps":["trace[2086700739] 'agreement among raft nodes before linearized reading' (duration: 161.03329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:51.057652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.504545ms","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-06-22T02:44:51.05769Z","caller":"traceutil/trace.go:171","msg":"trace[921624681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28803; }","duration":"152.570807ms","start":"2026-06-22T02:44:50.905107Z","end":"2026-06-22T02:44:51.057678Z","steps":["trace[921624681] 'agreement among raft nodes before linearized reading' (duration: 152.483405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:51.057845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.034546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-22T02:44:51.057878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.313244ms","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-06-22T02:44:51.057957Z","caller":"traceutil/trace.go:171","msg":"trace[1081076590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28803; }","duration":"165.476887ms","start":"2026-06-22T02:44:50.892463Z","end":"2026-06-22T02:44:51.05794Z","steps":["trace[1081076590] 'agreement among raft nodes before linearized reading' (duration: 165.241232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:51.057977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.054403ms","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-06-22T02:44:51.058022Z","caller":"traceutil/trace.go:171","msg":"trace[613409969] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28803; }","duration":"277.115394ms","start":"2026-06-22T02:44:50.780892Z","end":"2026-06-22T02:44:51.058007Z","steps":["trace[613409969] 'agreement among raft nodes before linearized reading' (duration: 277.046262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:51.058155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.488759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e3dd426f-1d70-43af-8c43-ad2862e26dca\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-06-22T02:44:51.057904Z","caller":"traceutil/trace.go:171","msg":"trace[899159873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28803; }","duration":"153.165669ms","start":"2026-06-22T02:44:50.904723Z","end":"2026-06-22T02:44:51.057888Z","steps":["trace[899159873] 'agreement among raft nodes before linearized reading' (duration: 153.029976ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:51.058201Z","caller":"traceutil/trace.go:171","msg":"trace[351812013] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e3dd426f-1d70-43af-8c43-ad2862e26dca; range_end:; response_count:1; response_revision:28803; }","duration":"177.570841ms","start":"2026-06-22T02:44:50.880618Z","end":"2026-06-22T02:44:51.058189Z","steps":["trace[351812013] 'agreement among raft nodes before linearized reading' (duration: 177.457108ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:44:56.342648Z","caller":"traceutil/trace.go:171","msg":"trace[326142763] linearizableReadLoop","detail":"{readStateIndex:30210; appliedIndex:30209; }","duration":"108.205683ms","start":"2026-06-22T02:44:56.234405Z","end":"2026-06-22T02:44:56.342611Z","steps":["trace[326142763] 'read index received' (duration: 107.817364ms)","trace[326142763] 'applied index is now lower than readState.Index' (duration: 386.949µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:44:56.342792Z","caller":"traceutil/trace.go:171","msg":"trace[500603686] transaction","detail":"{read_only:false; response_revision:28877; number_of_response:1; }","duration":"141.337163ms","start":"2026-06-22T02:44:56.201417Z","end":"2026-06-22T02:44:56.342754Z","steps":["trace[500603686] 'process raft request' (duration: 140.966715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:56.34298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.54207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-22T02:44:56.343041Z","caller":"traceutil/trace.go:171","msg":"trace[1578700853] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:28877; }","duration":"108.669192ms","start":"2026-06-22T02:44:56.234351Z","end":"2026-06-22T02:44:56.34302Z","steps":["trace[1578700853] 'agreement among raft nodes before linearized reading' (duration: 108.444118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:44:56.34321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.003018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:44:56.343266Z","caller":"traceutil/trace.go:171","msg":"trace[79733569] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:28877; }","duration":"108.095129ms","start":"2026-06-22T02:44:56.235156Z","end":"2026-06-22T02:44:56.343251Z","steps":["trace[79733569] 'agreement among raft nodes before linearized reading' (duration: 107.944226ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:03.838413Z","caller":"traceutil/trace.go:171","msg":"trace[829159065] transaction","detail":"{read_only:false; response_revision:28980; number_of_response:1; }","duration":"149.262736ms","start":"2026-06-22T02:45:03.689118Z","end":"2026-06-22T02:45:03.838381Z","steps":["trace[829159065] 'process raft request' (duration: 149.060601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:04.102407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.7661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:45:04.102615Z","caller":"traceutil/trace.go:171","msg":"trace[280669253] transaction","detail":"{read_only:false; response_revision:28981; number_of_response:1; }","duration":"327.463049ms","start":"2026-06-22T02:45:03.77513Z","end":"2026-06-22T02:45:04.102593Z","steps":["trace[280669253] 'process raft request' (duration: 152.414804ms)","trace[280669253] 'compare' (duration: 174.596636ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:04.102686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:03.775105Z","time spent":"327.548231ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:45:04.404367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.338446ms","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-06-22T02:45:04.404488Z","caller":"traceutil/trace.go:171","msg":"trace[546273558] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:28981; }","duration":"126.4834ms","start":"2026-06-22T02:45:04.277975Z","end":"2026-06-22T02:45:04.404459Z","steps":["trace[546273558] 'range keys from in-memory index tree' (duration: 126.099261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:04.404639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.427357ms","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:6"} {"level":"info","ts":"2026-06-22T02:45:04.404927Z","caller":"traceutil/trace.go:171","msg":"trace[553560963] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:28981; }","duration":"126.447639ms","start":"2026-06-22T02:45:04.278454Z","end":"2026-06-22T02:45:04.404901Z","steps":["trace[553560963] 'count revisions from in-memory index tree' (duration: 125.266553ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:04.650694Z","caller":"traceutil/trace.go:171","msg":"trace[591596156] linearizableReadLoop","detail":"{readStateIndex:30318; appliedIndex:30317; }","duration":"241.147343ms","start":"2026-06-22T02:45:04.409525Z","end":"2026-06-22T02:45:04.650672Z","steps":["trace[591596156] 'read index received' (duration: 240.963519ms)","trace[591596156] 'applied index is now lower than readState.Index' (duration: 182.534µs)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:04.650895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.364488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1dd66774-ef14-4306-bad1-a1c0f701e88a\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-22T02:45:04.650927Z","caller":"traceutil/trace.go:171","msg":"trace[1105959505] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1dd66774-ef14-4306-bad1-a1c0f701e88a; range_end:; response_count:1; response_revision:28981; }","duration":"241.45054ms","start":"2026-06-22T02:45:04.409466Z","end":"2026-06-22T02:45:04.650916Z","steps":["trace[1105959505] 'agreement among raft nodes before linearized reading' (duration: 241.287047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:04.863617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.414308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:45:04.863765Z","caller":"traceutil/trace.go:171","msg":"trace[603496506] linearizableReadLoop","detail":"{readStateIndex:30319; appliedIndex:30318; }","duration":"208.722227ms","start":"2026-06-22T02:45:04.655023Z","end":"2026-06-22T02:45:04.863746Z","steps":["trace[603496506] 'read index received' (duration: 70.87874ms)","trace[603496506] 'applied index is now lower than readState.Index' (duration: 137.841707ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:04.863845Z","caller":"traceutil/trace.go:171","msg":"trace[962951819] transaction","detail":"{read_only:false; response_revision:28982; number_of_response:1; }","duration":"212.187293ms","start":"2026-06-22T02:45:04.651605Z","end":"2026-06-22T02:45:04.863792Z","steps":["trace[962951819] 'process raft request' (duration: 74.341086ms)","trace[962951819] 'compare' (duration: 136.791674ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:04.863993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.955493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-22T02:45:04.864068Z","caller":"traceutil/trace.go:171","msg":"trace[882106019] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:28982; }","duration":"209.028695ms","start":"2026-06-22T02:45:04.655019Z","end":"2026-06-22T02:45:04.864047Z","steps":["trace[882106019] 'agreement among raft nodes before linearized reading' (duration: 208.81741ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.001859Z","caller":"traceutil/trace.go:171","msg":"trace[2078798928] transaction","detail":"{read_only:false; response_revision:29037; number_of_response:1; }","duration":"125.143082ms","start":"2026-06-22T02:45:08.876695Z","end":"2026-06-22T02:45:09.001838Z","steps":["trace[2078798928] 'process raft request' (duration: 124.994588ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.239066Z","caller":"traceutil/trace.go:171","msg":"trace[25711198] transaction","detail":"{read_only:false; response_revision:29038; number_of_response:1; }","duration":"178.597612ms","start":"2026-06-22T02:45:09.060428Z","end":"2026-06-22T02:45:09.239025Z","steps":["trace[25711198] 'process raft request' (duration: 178.385318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:09.378177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.400478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:45:09.37842Z","caller":"traceutil/trace.go:171","msg":"trace[1302755445] linearizableReadLoop","detail":"{readStateIndex:30377; appliedIndex:30375; }","duration":"181.965705ms","start":"2026-06-22T02:45:09.196428Z","end":"2026-06-22T02:45:09.378394Z","steps":["trace[1302755445] 'read index received' (duration: 42.455992ms)","trace[1302755445] 'applied index is now lower than readState.Index' (duration: 139.504893ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:09.378546Z","caller":"traceutil/trace.go:171","msg":"trace[196925527] transaction","detail":"{read_only:false; response_revision:29040; number_of_response:1; }","duration":"153.317542ms","start":"2026-06-22T02:45:09.225215Z","end":"2026-06-22T02:45:09.378532Z","steps":["trace[196925527] 'process raft request' (duration: 153.103538ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.378624Z","caller":"traceutil/trace.go:171","msg":"trace[1970700522] transaction","detail":"{read_only:false; response_revision:29039; number_of_response:1; }","duration":"250.755441ms","start":"2026-06-22T02:45:09.127793Z","end":"2026-06-22T02:45:09.378548Z","steps":["trace[1970700522] 'process raft request' (duration: 140.857092ms)","trace[1970700522] 'compare' (duration: 109.196604ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:09.378741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.024287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-22T02:45:09.378826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.384705ms","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-06-22T02:45:09.37885Z","caller":"traceutil/trace.go:171","msg":"trace[250936971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29040; }","duration":"182.14293ms","start":"2026-06-22T02:45:09.196682Z","end":"2026-06-22T02:45:09.378825Z","steps":["trace[250936971] 'agreement among raft nodes before linearized reading' (duration: 181.873304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:09.378875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.8983ms","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-06-22T02:45:09.378897Z","caller":"traceutil/trace.go:171","msg":"trace[945619854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29040; }","duration":"182.460977ms","start":"2026-06-22T02:45:09.196418Z","end":"2026-06-22T02:45:09.378879Z","steps":["trace[945619854] 'agreement among raft nodes before linearized reading' (duration: 182.274983ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.378923Z","caller":"traceutil/trace.go:171","msg":"trace[1406809277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29040; }","duration":"179.998483ms","start":"2026-06-22T02:45:09.198909Z","end":"2026-06-22T02:45:09.378907Z","steps":["trace[1406809277] 'agreement among raft nodes before linearized reading' (duration: 179.82778ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:09.378911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.079768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-22T02:45:09.378761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.28019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-06-22T02:45:09.379007Z","caller":"traceutil/trace.go:171","msg":"trace[935583235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29040; }","duration":"182.202351ms","start":"2026-06-22T02:45:09.196783Z","end":"2026-06-22T02:45:09.378986Z","steps":["trace[935583235] 'agreement among raft nodes before linearized reading' (duration: 181.950526ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.379018Z","caller":"traceutil/trace.go:171","msg":"trace[1822816956] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29040; }","duration":"175.553545ms","start":"2026-06-22T02:45:09.20345Z","end":"2026-06-22T02:45:09.379003Z","steps":["trace[1822816956] 'agreement among raft nodes before linearized reading' (duration: 175.103456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:09.379062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.307924ms","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-06-22T02:45:09.379094Z","caller":"traceutil/trace.go:171","msg":"trace[729104578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29040; }","duration":"182.393805ms","start":"2026-06-22T02:45:09.196691Z","end":"2026-06-22T02:45:09.379085Z","steps":["trace[729104578] 'agreement among raft nodes before linearized reading' (duration: 182.301853ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.591105Z","caller":"traceutil/trace.go:171","msg":"trace[82390927] linearizableReadLoop","detail":"{readStateIndex:30379; appliedIndex:30378; }","duration":"206.363716ms","start":"2026-06-22T02:45:09.384713Z","end":"2026-06-22T02:45:09.591077Z","steps":["trace[82390927] 'read index received' (duration: 124.441186ms)","trace[82390927] 'applied index is now lower than readState.Index' (duration: 81.91674ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:09.591153Z","caller":"traceutil/trace.go:171","msg":"trace[1180997952] transaction","detail":"{read_only:false; response_revision:29041; number_of_response:1; }","duration":"206.887618ms","start":"2026-06-22T02:45:09.384219Z","end":"2026-06-22T02:45:09.591107Z","steps":["trace[1180997952] 'process raft request' (duration: 124.803254ms)","trace[1180997952] 'compare' (duration: 81.838089ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:09.591334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.602431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-06-22T02:45:09.591445Z","caller":"traceutil/trace.go:171","msg":"trace[151466689] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29041; }","duration":"206.732395ms","start":"2026-06-22T02:45:09.384695Z","end":"2026-06-22T02:45:09.591427Z","steps":["trace[151466689] 'agreement among raft nodes before linearized reading' (duration: 206.477129ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.611477Z","caller":"traceutil/trace.go:171","msg":"trace[599947531] transaction","detail":"{read_only:false; response_revision:29042; number_of_response:1; }","duration":"226.684238ms","start":"2026-06-22T02:45:09.38475Z","end":"2026-06-22T02:45:09.611434Z","steps":["trace[599947531] 'process raft request' (duration: 226.354621ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.611562Z","caller":"traceutil/trace.go:171","msg":"trace[427663452] transaction","detail":"{read_only:false; response_revision:29044; number_of_response:1; }","duration":"226.729768ms","start":"2026-06-22T02:45:09.384806Z","end":"2026-06-22T02:45:09.611536Z","steps":["trace[427663452] 'process raft request' (duration: 226.613745ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.611579Z","caller":"traceutil/trace.go:171","msg":"trace[616436592] transaction","detail":"{read_only:false; response_revision:29043; number_of_response:1; }","duration":"226.752319ms","start":"2026-06-22T02:45:09.384777Z","end":"2026-06-22T02:45:09.611529Z","steps":["trace[616436592] 'process raft request' (duration: 226.560944ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.611625Z","caller":"traceutil/trace.go:171","msg":"trace[417685977] transaction","detail":"{read_only:false; response_revision:29045; number_of_response:1; }","duration":"226.190937ms","start":"2026-06-22T02:45:09.3854Z","end":"2026-06-22T02:45:09.611591Z","steps":["trace[417685977] 'process raft request' (duration: 226.068254ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.611715Z","caller":"traceutil/trace.go:171","msg":"trace[816450608] transaction","detail":"{read_only:false; response_revision:29046; number_of_response:1; }","duration":"226.207778ms","start":"2026-06-22T02:45:09.385486Z","end":"2026-06-22T02:45:09.611694Z","steps":["trace[816450608] 'process raft request' (duration: 226.027693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:09.611746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.714234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:45:09.611861Z","caller":"traceutil/trace.go:171","msg":"trace[415772767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29047; }","duration":"137.893738ms","start":"2026-06-22T02:45:09.473943Z","end":"2026-06-22T02:45:09.611836Z","steps":["trace[415772767] 'agreement among raft nodes before linearized reading' (duration: 137.689643ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:09.611764Z","caller":"traceutil/trace.go:171","msg":"trace[752049044] transaction","detail":"{read_only:false; response_revision:29047; number_of_response:1; }","duration":"224.117593ms","start":"2026-06-22T02:45:09.387601Z","end":"2026-06-22T02:45:09.611719Z","steps":["trace[752049044] 'process raft request' (duration: 223.954848ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:11.213418Z","caller":"traceutil/trace.go:171","msg":"trace[273865242] transaction","detail":"{read_only:false; response_revision:29058; number_of_response:1; }","duration":"205.958317ms","start":"2026-06-22T02:45:11.007414Z","end":"2026-06-22T02:45:11.213372Z","steps":["trace[273865242] 'process raft request' (duration: 113.420475ms)","trace[273865242] 'compare' (duration: 92.213115ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:30.221788Z","caller":"traceutil/trace.go:171","msg":"trace[484021226] transaction","detail":"{read_only:false; response_revision:29256; number_of_response:1; }","duration":"342.504267ms","start":"2026-06-22T02:45:29.879256Z","end":"2026-06-22T02:45:30.22176Z","steps":["trace[484021226] 'process raft request' (duration: 342.313923ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:30.221979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:29.87921Z","time spent":"342.669971ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:45:30.261141Z","caller":"traceutil/trace.go:171","msg":"trace[1435976478] transaction","detail":"{read_only:false; response_revision:29258; number_of_response:1; }","duration":"323.974333ms","start":"2026-06-22T02:45:29.937122Z","end":"2026-06-22T02:45:30.261097Z","steps":["trace[1435976478] 'process raft request' (duration: 323.81609ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:30.26127Z","caller":"traceutil/trace.go:171","msg":"trace[1563042309] transaction","detail":"{read_only:false; response_revision:29257; number_of_response:1; }","duration":"381.568845ms","start":"2026-06-22T02:45:29.879642Z","end":"2026-06-22T02:45:30.261211Z","steps":["trace[1563042309] 'process raft request' (duration: 381.079754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:30.261337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:29.937091Z","time spent":"324.138697ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:45:30.261498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:29.879609Z","time spent":"381.770149ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:45:30.53277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.612828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-22T02:45:30.532937Z","caller":"traceutil/trace.go:171","msg":"trace[1428773056] transaction","detail":"{read_only:false; response_revision:29260; number_of_response:1; }","duration":"267.1827ms","start":"2026-06-22T02:45:30.265727Z","end":"2026-06-22T02:45:30.53291Z","steps":["trace[1428773056] 'process raft request' (duration: 120.311256ms)","trace[1428773056] 'compare' (duration: 146.353963ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:30.552239Z","caller":"traceutil/trace.go:171","msg":"trace[1422105461] linearizableReadLoop","detail":"{readStateIndex:30608; appliedIndex:30607; }","duration":"140.445594ms","start":"2026-06-22T02:45:30.411754Z","end":"2026-06-22T02:45:30.5522Z","steps":["trace[1422105461] 'read index received' (duration: 140.204719ms)","trace[1422105461] 'applied index is now lower than readState.Index' (duration: 239.095µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:30.552372Z","caller":"traceutil/trace.go:171","msg":"trace[237121888] transaction","detail":"{read_only:false; response_revision:29261; number_of_response:1; }","duration":"282.181515ms","start":"2026-06-22T02:45:30.270145Z","end":"2026-06-22T02:45:30.552326Z","steps":["trace[237121888] 'process raft request' (duration: 281.856338ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:30.552608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.313761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-22T02:45:30.552671Z","caller":"traceutil/trace.go:171","msg":"trace[1513679571] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:29261; }","duration":"106.492005ms","start":"2026-06-22T02:45:30.446164Z","end":"2026-06-22T02:45:30.552656Z","steps":["trace[1513679571] 'agreement among raft nodes before linearized reading' (duration: 106.25739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:30.552786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.013706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-22T02:45:30.552918Z","caller":"traceutil/trace.go:171","msg":"trace[154151753] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:29261; }","duration":"141.241432ms","start":"2026-06-22T02:45:30.411652Z","end":"2026-06-22T02:45:30.552894Z","steps":["trace[154151753] 'agreement among raft nodes before linearized reading' (duration: 140.6893ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:31.398185Z","caller":"traceutil/trace.go:171","msg":"trace[779335908] transaction","detail":"{read_only:false; response_revision:29262; number_of_response:1; }","duration":"113.007317ms","start":"2026-06-22T02:45:31.285155Z","end":"2026-06-22T02:45:31.398163Z","steps":["trace[779335908] 'process raft request' (duration: 112.863104ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:31.446862Z","caller":"traceutil/trace.go:171","msg":"trace[193790133] transaction","detail":"{read_only:false; response_revision:29263; number_of_response:1; }","duration":"125.57543ms","start":"2026-06-22T02:45:31.321222Z","end":"2026-06-22T02:45:31.446798Z","steps":["trace[193790133] 'process raft request' (duration: 125.10846ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:31.915772Z","caller":"traceutil/trace.go:171","msg":"trace[827116291] linearizableReadLoop","detail":"{readStateIndex:30612; appliedIndex:30611; }","duration":"242.636195ms","start":"2026-06-22T02:45:31.673096Z","end":"2026-06-22T02:45:31.915732Z","steps":["trace[827116291] 'read index received' (duration: 242.279297ms)","trace[827116291] 'applied index is now lower than readState.Index' (duration: 355.108µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:31.915872Z","caller":"traceutil/trace.go:171","msg":"trace[402920242] transaction","detail":"{read_only:false; response_revision:29265; number_of_response:1; }","duration":"345.524271ms","start":"2026-06-22T02:45:31.570306Z","end":"2026-06-22T02:45:31.91583Z","steps":["trace[402920242] 'process raft request' (duration: 345.165933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:31.916046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.90946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-22T02:45:31.916118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:31.570274Z","time spent":"345.690005ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:45:31.916145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.76409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-22T02:45:31.916193Z","caller":"traceutil/trace.go:171","msg":"trace[1321113662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29265; }","duration":"209.882732ms","start":"2026-06-22T02:45:31.706298Z","end":"2026-06-22T02:45:31.916181Z","steps":["trace[1321113662] 'agreement among raft nodes before linearized reading' (duration: 209.654017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:31.916228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.257234ms","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-06-22T02:45:31.916243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.06285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-22T02:45:31.916295Z","caller":"traceutil/trace.go:171","msg":"trace[1533608729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29265; }","duration":"194.354555ms","start":"2026-06-22T02:45:31.72192Z","end":"2026-06-22T02:45:31.916274Z","steps":["trace[1533608729] 'agreement among raft nodes before linearized reading' (duration: 194.190771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:31.916283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.049099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-22T02:45:31.916338Z","caller":"traceutil/trace.go:171","msg":"trace[2035356949] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29265; }","duration":"188.195282ms","start":"2026-06-22T02:45:31.728121Z","end":"2026-06-22T02:45:31.916317Z","steps":["trace[2035356949] 'agreement among raft nodes before linearized reading' (duration: 187.895745ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:31.916363Z","caller":"traceutil/trace.go:171","msg":"trace[1571903434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29265; }","duration":"188.128041ms","start":"2026-06-22T02:45:31.728219Z","end":"2026-06-22T02:45:31.916347Z","steps":["trace[1571903434] 'agreement among raft nodes before linearized reading' (duration: 187.967507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:31.91628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.323185ms","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-06-22T02:45:31.916412Z","caller":"traceutil/trace.go:171","msg":"trace[1134478654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29265; }","duration":"188.481478ms","start":"2026-06-22T02:45:31.727918Z","end":"2026-06-22T02:45:31.9164Z","steps":["trace[1134478654] 'agreement among raft nodes before linearized reading' (duration: 188.229642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:31.916449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.926219ms","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-06-22T02:45:31.916557Z","caller":"traceutil/trace.go:171","msg":"trace[124288253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29265; }","duration":"196.104823ms","start":"2026-06-22T02:45:31.720425Z","end":"2026-06-22T02:45:31.91653Z","steps":["trace[124288253] 'agreement among raft nodes before linearized reading' (duration: 195.752024ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:31.916145Z","caller":"traceutil/trace.go:171","msg":"trace[1621054761] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:29265; }","duration":"243.080184ms","start":"2026-06-22T02:45:31.673039Z","end":"2026-06-22T02:45:31.916119Z","steps":["trace[1621054761] 'agreement among raft nodes before linearized reading' (duration: 242.88422ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.114717Z","caller":"traceutil/trace.go:171","msg":"trace[2083421722] transaction","detail":"{read_only:false; response_revision:29266; number_of_response:1; }","duration":"193.019106ms","start":"2026-06-22T02:45:31.921669Z","end":"2026-06-22T02:45:32.114688Z","steps":["trace[2083421722] 'process raft request' (duration: 115.374099ms)","trace[2083421722] 'compare' (duration: 77.435553ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:32.115047Z","caller":"traceutil/trace.go:171","msg":"trace[905615636] transaction","detail":"{read_only:false; response_revision:29270; number_of_response:1; }","duration":"192.921723ms","start":"2026-06-22T02:45:31.922092Z","end":"2026-06-22T02:45:32.115014Z","steps":["trace[905615636] 'process raft request' (duration: 192.864172ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.115101Z","caller":"traceutil/trace.go:171","msg":"trace[1361045367] transaction","detail":"{read_only:false; response_revision:29268; number_of_response:1; }","duration":"193.089237ms","start":"2026-06-22T02:45:31.921952Z","end":"2026-06-22T02:45:32.115041Z","steps":["trace[1361045367] 'process raft request' (duration: 192.880742ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.115218Z","caller":"traceutil/trace.go:171","msg":"trace[790491504] transaction","detail":"{read_only:false; response_revision:29269; number_of_response:1; }","duration":"193.179749ms","start":"2026-06-22T02:45:31.922032Z","end":"2026-06-22T02:45:32.115212Z","steps":["trace[790491504] 'process raft request' (duration: 192.893982ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.115373Z","caller":"traceutil/trace.go:171","msg":"trace[1353500191] transaction","detail":"{read_only:false; response_revision:29271; number_of_response:1; }","duration":"193.251381ms","start":"2026-06-22T02:45:31.922103Z","end":"2026-06-22T02:45:32.115354Z","steps":["trace[1353500191] 'process raft request' (duration: 192.881612ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.115391Z","caller":"traceutil/trace.go:171","msg":"trace[1041475579] transaction","detail":"{read_only:false; response_revision:29267; number_of_response:1; }","duration":"193.62922ms","start":"2026-06-22T02:45:31.921747Z","end":"2026-06-22T02:45:32.115376Z","steps":["trace[1041475579] 'process raft request' (duration: 192.876723ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.317067Z","caller":"traceutil/trace.go:171","msg":"trace[1625818113] linearizableReadLoop","detail":"{readStateIndex:30619; appliedIndex:30618; }","duration":"111.382301ms","start":"2026-06-22T02:45:32.20566Z","end":"2026-06-22T02:45:32.317043Z","steps":["trace[1625818113] 'read index received' (duration: 99.86626ms)","trace[1625818113] 'applied index is now lower than readState.Index' (duration: 11.514361ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:32.317243Z","caller":"traceutil/trace.go:171","msg":"trace[1725550843] transaction","detail":"{read_only:false; response_revision:29272; number_of_response:1; }","duration":"119.884986ms","start":"2026-06-22T02:45:32.197309Z","end":"2026-06-22T02:45:32.317194Z","steps":["trace[1725550843] 'process raft request' (duration: 108.197221ms)","trace[1725550843] 'compare' (duration: 11.42105ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:32.317397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.658938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-06-22T02:45:32.317473Z","caller":"traceutil/trace.go:171","msg":"trace[87860505] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:29272; }","duration":"111.843482ms","start":"2026-06-22T02:45:32.205609Z","end":"2026-06-22T02:45:32.317452Z","steps":["trace[87860505] 'agreement among raft nodes before linearized reading' (duration: 111.603507ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.500754Z","caller":"traceutil/trace.go:171","msg":"trace[1133216199] transaction","detail":"{read_only:false; response_revision:29275; number_of_response:1; }","duration":"121.545012ms","start":"2026-06-22T02:45:32.379176Z","end":"2026-06-22T02:45:32.500721Z","steps":["trace[1133216199] 'process raft request' (duration: 121.272696ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:32.631339Z","caller":"traceutil/trace.go:171","msg":"trace[933232001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:29276; }","duration":"109.943289ms","start":"2026-06-22T02:45:32.521374Z","end":"2026-06-22T02:45:32.631317Z","steps":["trace[933232001] 'process raft request' (duration: 61.042676ms)","trace[933232001] 'compare' (duration: 48.813461ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:32.90708Z","caller":"traceutil/trace.go:171","msg":"trace[572432202] transaction","detail":"{read_only:false; response_revision:29280; number_of_response:1; }","duration":"182.705052ms","start":"2026-06-22T02:45:32.724333Z","end":"2026-06-22T02:45:32.907038Z","steps":["trace[572432202] 'process raft request' (duration: 121.942832ms)","trace[572432202] 'compare' (duration: 60.593526ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:32.938312Z","caller":"traceutil/trace.go:171","msg":"trace[1702409849] linearizableReadLoop","detail":"{readStateIndex:30629; appliedIndex:30627; }","duration":"195.836398ms","start":"2026-06-22T02:45:32.742438Z","end":"2026-06-22T02:45:32.938274Z","steps":["trace[1702409849] 'read index received' (duration: 103.854939ms)","trace[1702409849] 'applied index is now lower than readState.Index' (duration: 91.979819ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:32.938493Z","caller":"traceutil/trace.go:171","msg":"trace[1658742758] transaction","detail":"{read_only:false; response_revision:29281; number_of_response:1; }","duration":"211.260872ms","start":"2026-06-22T02:45:32.727212Z","end":"2026-06-22T02:45:32.938472Z","steps":["trace[1658742758] 'process raft request' (duration: 210.843503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:32.938691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.527515ms","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-06-22T02:45:32.938813Z","caller":"traceutil/trace.go:171","msg":"trace[603703582] 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:29281; }","duration":"186.697579ms","start":"2026-06-22T02:45:32.752084Z","end":"2026-06-22T02:45:32.938781Z","steps":["trace[603703582] 'agreement among raft nodes before linearized reading' (duration: 186.486343ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:32.93887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.393891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-06-22T02:45:32.938963Z","caller":"traceutil/trace.go:171","msg":"trace[2011823451] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:29281; }","duration":"196.564404ms","start":"2026-06-22T02:45:32.74238Z","end":"2026-06-22T02:45:32.938944Z","steps":["trace[2011823451] 'agreement among raft nodes before linearized reading' (duration: 196.247746ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:33.637058Z","caller":"traceutil/trace.go:171","msg":"trace[72336931] transaction","detail":"{read_only:false; response_revision:29282; number_of_response:1; }","duration":"233.037946ms","start":"2026-06-22T02:45:33.403965Z","end":"2026-06-22T02:45:33.637003Z","steps":["trace[72336931] 'process raft request' (duration: 232.674729ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:33.964649Z","caller":"traceutil/trace.go:171","msg":"trace[482697071] linearizableReadLoop","detail":"{readStateIndex:30631; appliedIndex:30629; }","duration":"433.243799ms","start":"2026-06-22T02:45:33.531372Z","end":"2026-06-22T02:45:33.964615Z","steps":["trace[482697071] 'read index received' (duration: 105.256588ms)","trace[482697071] 'applied index is now lower than readState.Index' (duration: 327.986041ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:33.964829Z","caller":"traceutil/trace.go:171","msg":"trace[1933842134] transaction","detail":"{read_only:false; response_revision:29283; number_of_response:1; }","duration":"509.107499ms","start":"2026-06-22T02:45:33.455696Z","end":"2026-06-22T02:45:33.964803Z","steps":["trace[1933842134] 'process raft request' (duration: 508.562877ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:33.964878Z","caller":"traceutil/trace.go:171","msg":"trace[1660964503] transaction","detail":"{read_only:false; response_revision:29284; number_of_response:1; }","duration":"409.138675ms","start":"2026-06-22T02:45:33.555726Z","end":"2026-06-22T02:45:33.964865Z","steps":["trace[1660964503] 'process raft request' (duration: 408.819568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:33.964997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:33.455672Z","time spent":"509.213241ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:45:33.965035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:33.555703Z","time spent":"409.262967ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:45:33.965425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.023633ms","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:6"} {"level":"warn","ts":"2026-06-22T02:45:33.965503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.456495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-22T02:45:33.965521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.114878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-22T02:45:33.965567Z","caller":"traceutil/trace.go:171","msg":"trace[698085697] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:29284; }","duration":"187.563398ms","start":"2026-06-22T02:45:33.777984Z","end":"2026-06-22T02:45:33.965547Z","steps":["trace[698085697] 'agreement among raft nodes before linearized reading' (duration: 187.439395ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:33.965551Z","caller":"traceutil/trace.go:171","msg":"trace[407126085] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:29284; }","duration":"409.36984ms","start":"2026-06-22T02:45:33.556151Z","end":"2026-06-22T02:45:33.965521Z","steps":["trace[407126085] 'agreement among raft nodes before linearized reading' (duration: 409.069184ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:33.965611Z","caller":"traceutil/trace.go:171","msg":"trace[1736523201] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:29284; }","duration":"434.262361ms","start":"2026-06-22T02:45:33.531328Z","end":"2026-06-22T02:45:33.96559Z","steps":["trace[1736523201] 'agreement among raft nodes before linearized reading' (duration: 433.938674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:33.965633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:33.556141Z","time spent":"409.479732ms","remote":"127.0.0.1:37964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-22T02:45:33.965659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:33.531309Z","time spent":"434.341203ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":87,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-06-22T02:45:34.242973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.198287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-06-22T02:45:34.243059Z","caller":"traceutil/trace.go:171","msg":"trace[469043709] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29284; }","duration":"122.332559ms","start":"2026-06-22T02:45:34.120708Z","end":"2026-06-22T02:45:34.243041Z","steps":["trace[469043709] 'range keys from in-memory index tree' (duration: 122.000563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:34.243246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.210846ms","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-06-22T02:45:34.243323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.695517ms","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-06-22T02:45:34.243323Z","caller":"traceutil/trace.go:171","msg":"trace[839182729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29284; }","duration":"122.307508ms","start":"2026-06-22T02:45:34.120992Z","end":"2026-06-22T02:45:34.2433Z","steps":["trace[839182729] 'range keys from in-memory index tree' (duration: 122.070713ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.243346Z","caller":"traceutil/trace.go:171","msg":"trace[884608787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:29284; }","duration":"122.729078ms","start":"2026-06-22T02:45:34.120609Z","end":"2026-06-22T02:45:34.243338Z","steps":["trace[884608787] 'range keys from in-memory index tree' (duration: 122.628456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:34.243531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.8076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-22T02:45:34.243559Z","caller":"traceutil/trace.go:171","msg":"trace[1604814946] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:29284; }","duration":"122.84696ms","start":"2026-06-22T02:45:34.120707Z","end":"2026-06-22T02:45:34.243554Z","steps":["trace[1604814946] 'range keys from in-memory index tree' (duration: 122.749419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:34.243516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.524155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-22T02:45:34.243662Z","caller":"traceutil/trace.go:171","msg":"trace[1921065662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:29284; }","duration":"123.724519ms","start":"2026-06-22T02:45:34.119911Z","end":"2026-06-22T02:45:34.243635Z","steps":["trace[1921065662] 'range keys from in-memory index tree' (duration: 123.243549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:34.243745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.378553ms","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-06-22T02:45:34.243796Z","caller":"traceutil/trace.go:171","msg":"trace[854660489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:29284; }","duration":"124.470005ms","start":"2026-06-22T02:45:34.119312Z","end":"2026-06-22T02:45:34.243782Z","steps":["trace[854660489] 'range keys from in-memory index tree' (duration: 124.18956ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.42234Z","caller":"traceutil/trace.go:171","msg":"trace[1364275505] transaction","detail":"{read_only:false; response_revision:29287; number_of_response:1; }","duration":"174.759059ms","start":"2026-06-22T02:45:34.247546Z","end":"2026-06-22T02:45:34.422305Z","steps":["trace[1364275505] 'process raft request' (duration: 174.638606ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.422369Z","caller":"traceutil/trace.go:171","msg":"trace[2136850166] transaction","detail":"{read_only:false; response_revision:29286; number_of_response:1; }","duration":"174.79861ms","start":"2026-06-22T02:45:34.247521Z","end":"2026-06-22T02:45:34.422319Z","steps":["trace[2136850166] 'process raft request' (duration: 174.437222ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.422697Z","caller":"traceutil/trace.go:171","msg":"trace[2059710164] transaction","detail":"{read_only:false; response_revision:29289; number_of_response:1; }","duration":"174.267938ms","start":"2026-06-22T02:45:34.248403Z","end":"2026-06-22T02:45:34.422671Z","steps":["trace[2059710164] 'process raft request' (duration: 174.072104ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.422683Z","caller":"traceutil/trace.go:171","msg":"trace[227011424] transaction","detail":"{read_only:false; response_revision:29288; number_of_response:1; }","duration":"174.697457ms","start":"2026-06-22T02:45:34.247934Z","end":"2026-06-22T02:45:34.422631Z","steps":["trace[227011424] 'process raft request' (duration: 174.318539ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.422716Z","caller":"traceutil/trace.go:171","msg":"trace[231621629] transaction","detail":"{read_only:false; response_revision:29290; number_of_response:1; }","duration":"173.354379ms","start":"2026-06-22T02:45:34.249345Z","end":"2026-06-22T02:45:34.422699Z","steps":["trace[231621629] 'process raft request' (duration: 173.257447ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.692082Z","caller":"traceutil/trace.go:171","msg":"trace[356498560] transaction","detail":"{read_only:false; response_revision:29291; number_of_response:1; }","duration":"365.067276ms","start":"2026-06-22T02:45:34.326964Z","end":"2026-06-22T02:45:34.692031Z","steps":["trace[356498560] 'process raft request' (duration: 289.046054ms)","trace[356498560] 'compare' (duration: 75.864429ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:34.692214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:34.326931Z","time spent":"365.2119ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-22T02:45:34.692682Z","caller":"traceutil/trace.go:171","msg":"trace[371265527] transaction","detail":"{read_only:false; response_revision:29292; number_of_response:1; }","duration":"363.782229ms","start":"2026-06-22T02:45:34.328883Z","end":"2026-06-22T02:45:34.692665Z","steps":["trace[371265527] 'process raft request' (duration: 363.106613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T02:45:34.692835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:34.328866Z","time spent":"363.87097ms","remote":"127.0.0.1:52034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-22T02:45:34.693117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.925448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-22T02:45:34.693211Z","caller":"traceutil/trace.go:171","msg":"trace[2109906305] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:29292; }","duration":"356.097032ms","start":"2026-06-22T02:45:34.337092Z","end":"2026-06-22T02:45:34.693189Z","steps":["trace[2109906305] 'agreement among raft nodes before linearized reading' (duration: 355.713954ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.692256Z","caller":"traceutil/trace.go:171","msg":"trace[2108420594] linearizableReadLoop","detail":"{readStateIndex:30640; appliedIndex:30633; }","duration":"355.102659ms","start":"2026-06-22T02:45:34.337119Z","end":"2026-06-22T02:45:34.692222Z","steps":["trace[2108420594] 'read index received' (duration: 84.865405ms)","trace[2108420594] 'applied index is now lower than readState.Index' (duration: 270.235974ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:34.693274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-22T02:45:34.337084Z","time spent":"356.170873ms","remote":"127.0.0.1:51822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-06-22T02:45:34.693384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.939942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:45:34.693442Z","caller":"traceutil/trace.go:171","msg":"trace[1618819380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29292; }","duration":"220.027114ms","start":"2026-06-22T02:45:34.473396Z","end":"2026-06-22T02:45:34.693423Z","steps":["trace[1618819380] 'agreement among raft nodes before linearized reading' (duration: 219.935892ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:34.928551Z","caller":"traceutil/trace.go:171","msg":"trace[1834836397] linearizableReadLoop","detail":"{readStateIndex:30642; appliedIndex:30641; }","duration":"115.566772ms","start":"2026-06-22T02:45:34.81296Z","end":"2026-06-22T02:45:34.928527Z","steps":["trace[1834836397] 'read index received' (duration: 85.0824ms)","trace[1834836397] 'applied index is now lower than readState.Index' (duration: 30.482312ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:34.928562Z","caller":"traceutil/trace.go:171","msg":"trace[121801452] transaction","detail":"{read_only:false; response_revision:29293; number_of_response:1; }","duration":"155.766226ms","start":"2026-06-22T02:45:34.772767Z","end":"2026-06-22T02:45:34.928533Z","steps":["trace[121801452] 'process raft request' (duration: 125.347095ms)","trace[121801452] 'compare' (duration: 30.007733ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:34.928786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.783657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-22T02:45:34.928835Z","caller":"traceutil/trace.go:171","msg":"trace[1723931717] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:29293; }","duration":"115.976521ms","start":"2026-06-22T02:45:34.812843Z","end":"2026-06-22T02:45:34.92882Z","steps":["trace[1723931717] 'agreement among raft nodes before linearized reading' (duration: 115.812867ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:45:35.107251Z","caller":"traceutil/trace.go:171","msg":"trace[994137852] linearizableReadLoop","detail":"{readStateIndex:30643; appliedIndex:30642; }","duration":"163.524095ms","start":"2026-06-22T02:45:34.943702Z","end":"2026-06-22T02:45:35.107226Z","steps":["trace[994137852] 'read index received' (duration: 103.365527ms)","trace[994137852] 'applied index is now lower than readState.Index' (duration: 60.157568ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T02:45:35.107289Z","caller":"traceutil/trace.go:171","msg":"trace[725812292] transaction","detail":"{read_only:false; response_revision:29294; number_of_response:1; }","duration":"168.034793ms","start":"2026-06-22T02:45:34.939236Z","end":"2026-06-22T02:45:35.107271Z","steps":["trace[725812292] 'process raft request' (duration: 107.852705ms)","trace[725812292] 'compare' (duration: 60.023885ms)"],"step_count":2} {"level":"warn","ts":"2026-06-22T02:45:35.107473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.75191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-22T02:45:35.107512Z","caller":"traceutil/trace.go:171","msg":"trace[1547931213] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:29294; }","duration":"163.812531ms","start":"2026-06-22T02:45:34.943689Z","end":"2026-06-22T02:45:35.107501Z","steps":["trace[1547931213] 'agreement among raft nodes before linearized reading' (duration: 163.622967ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T02:47:11.136504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27068} {"level":"info","ts":"2026-06-22T02:47:11.298826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27068,"took":"158.535728ms","hash":2254157283,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":36102144,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-22T02:47:11.298917Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2254157283,"revision":27068,"compact-revision":23420} {"level":"info","ts":"2026-06-22T02:52:11.144603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30370} {"level":"info","ts":"2026-06-22T02:52:11.296879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30370,"took":"147.313994ms","hash":2075305415,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":36794368,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-22T02:52:11.296941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2075305415,"revision":30370,"compact-revision":27068} {"level":"info","ts":"2026-06-22T02:57:11.154413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33456} {"level":"info","ts":"2026-06-22T02:57:11.29479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33456,"took":"135.531029ms","hash":729990888,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":34447360,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-22T02:57:11.294867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":729990888,"revision":33456,"compact-revision":30370} {"level":"info","ts":"2026-06-22T03:00:37.670245Z","caller":"traceutil/trace.go:171","msg":"trace[1067982258] transaction","detail":"{read_only:false; response_revision:38080; number_of_response:1; }","duration":"167.429014ms","start":"2026-06-22T03:00:37.50277Z","end":"2026-06-22T03:00:37.670199Z","steps":["trace[1067982258] 'process raft request' (duration: 167.054505ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:00:57.798473Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-22T03:00:57.806117Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-22T03:00:57.80633Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-22T03:02:11.166803Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36126} {"level":"info","ts":"2026-06-22T03:02:11.301127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36126,"took":"130.088411ms","hash":275390008,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":34713600,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-22T03:02:11.301228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":275390008,"revision":36126,"compact-revision":33456} {"level":"info","ts":"2026-06-22T03:07:11.176538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38903} {"level":"info","ts":"2026-06-22T03:07:11.340985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38903,"took":"159.154744ms","hash":1544071004,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":35282944,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-22T03:07:11.341079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1544071004,"revision":38903,"compact-revision":36126} {"level":"info","ts":"2026-06-22T03:12:11.183531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41672} {"level":"info","ts":"2026-06-22T03:12:11.34999Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41672,"took":"160.982831ms","hash":2832141356,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":33886208,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-22T03:12:11.350083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2832141356,"revision":41672,"compact-revision":38903} {"level":"info","ts":"2026-06-22T03:14:13.631762Z","caller":"traceutil/trace.go:171","msg":"trace[1049782110] transaction","detail":"{read_only:false; response_revision:45468; number_of_response:1; }","duration":"145.410484ms","start":"2026-06-22T03:14:13.486333Z","end":"2026-06-22T03:14:13.631743Z","steps":["trace[1049782110] 'process raft request' (duration: 145.297701ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:14:13.633842Z","caller":"traceutil/trace.go:171","msg":"trace[335759912] transaction","detail":"{read_only:false; response_revision:45469; number_of_response:1; }","duration":"145.523126ms","start":"2026-06-22T03:14:13.488299Z","end":"2026-06-22T03:14:13.633822Z","steps":["trace[335759912] 'process raft request' (duration: 145.403774ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:17:11.189755Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44344} {"level":"info","ts":"2026-06-22T03:17:11.329384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44344,"took":"134.028396ms","hash":1327758156,"current-db-size-bytes":60768256,"current-db-size":"61 MB","current-db-size-in-use-bytes":33140736,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-22T03:17:11.329503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1327758156,"revision":44344,"compact-revision":41672} {"level":"info","ts":"2026-06-22T03:17:42.324764Z","caller":"traceutil/trace.go:171","msg":"trace[2111420462] transaction","detail":"{read_only:false; response_revision:47365; number_of_response:1; }","duration":"208.965236ms","start":"2026-06-22T03:17:42.115768Z","end":"2026-06-22T03:17:42.324734Z","steps":["trace[2111420462] 'process raft request' (duration: 208.783922ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:17:42.326276Z","caller":"traceutil/trace.go:171","msg":"trace[986974498] transaction","detail":"{read_only:false; response_revision:47366; number_of_response:1; }","duration":"173.752139ms","start":"2026-06-22T03:17:42.152494Z","end":"2026-06-22T03:17:42.326246Z","steps":["trace[986974498] 'process raft request' (duration: 173.165877ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:17:42.326366Z","caller":"traceutil/trace.go:171","msg":"trace[2077474002] linearizableReadLoop","detail":"{readStateIndex:49523; appliedIndex:49519; }","duration":"141.346794ms","start":"2026-06-22T03:17:42.184974Z","end":"2026-06-22T03:17:42.326321Z","steps":["trace[2077474002] 'read index received' (duration: 139.567756ms)","trace[2077474002] 'applied index is now lower than readState.Index' (duration: 1.777598ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T03:17:42.326431Z","caller":"traceutil/trace.go:171","msg":"trace[1395384765] transaction","detail":"{read_only:false; response_revision:47368; number_of_response:1; }","duration":"168.770432ms","start":"2026-06-22T03:17:42.157631Z","end":"2026-06-22T03:17:42.326401Z","steps":["trace[1395384765] 'process raft request' (duration: 168.640748ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:17:42.326452Z","caller":"traceutil/trace.go:171","msg":"trace[1473328539] transaction","detail":"{read_only:false; response_revision:47367; number_of_response:1; }","duration":"172.84854ms","start":"2026-06-22T03:17:42.153536Z","end":"2026-06-22T03:17:42.326384Z","steps":["trace[1473328539] 'process raft request' (duration: 172.678076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T03:17:42.326742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.715664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-senlin-nodes\" ","response":"range_response_count:1 size:958"} {"level":"warn","ts":"2026-06-22T03:17:42.326781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.14383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-06-22T03:17:42.326857Z","caller":"traceutil/trace.go:171","msg":"trace[1138973482] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-senlin-nodes; range_end:; response_count:1; response_revision:47368; }","duration":"141.902797ms","start":"2026-06-22T03:17:42.184923Z","end":"2026-06-22T03:17:42.326825Z","steps":["trace[1138973482] 'agreement among raft nodes before linearized reading' (duration: 141.536739ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:17:42.326874Z","caller":"traceutil/trace.go:171","msg":"trace[78053702] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:47368; }","duration":"101.307164ms","start":"2026-06-22T03:17:42.225541Z","end":"2026-06-22T03:17:42.326848Z","steps":["trace[78053702] 'agreement among raft nodes before linearized reading' (duration: 101.09316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T03:17:42.326881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.263006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rook-ceph-rgw-ceph\" ","response":"range_response_count:1 size:1422"} {"level":"info","ts":"2026-06-22T03:17:42.327009Z","caller":"traceutil/trace.go:171","msg":"trace[562327473] range","detail":"{range_begin:/registry/services/endpoints/openstack/rook-ceph-rgw-ceph; range_end:; response_count:1; response_revision:47368; }","duration":"120.45453ms","start":"2026-06-22T03:17:42.206523Z","end":"2026-06-22T03:17:42.326977Z","steps":["trace[562327473] 'agreement among raft nodes before linearized reading' (duration: 120.032641ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:18:07.739086Z","caller":"traceutil/trace.go:171","msg":"trace[522369007] linearizableReadLoop","detail":"{readStateIndex:49748; appliedIndex:49747; }","duration":"146.772422ms","start":"2026-06-22T03:18:07.592186Z","end":"2026-06-22T03:18:07.738959Z","steps":["trace[522369007] 'read index received' (duration: 146.337043ms)","trace[522369007] 'applied index is now lower than readState.Index' (duration: 433.679µs)"],"step_count":2} {"level":"info","ts":"2026-06-22T03:18:07.739456Z","caller":"traceutil/trace.go:171","msg":"trace[552886167] transaction","detail":"{read_only:false; response_revision:47583; number_of_response:1; }","duration":"150.827151ms","start":"2026-06-22T03:18:07.588547Z","end":"2026-06-22T03:18:07.739374Z","steps":["trace[552886167] 'process raft request' (duration: 150.121676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T03:18:07.740298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.619904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-22T03:18:07.740432Z","caller":"traceutil/trace.go:171","msg":"trace[356991712] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:47583; }","duration":"132.783488ms","start":"2026-06-22T03:18:07.607579Z","end":"2026-06-22T03:18:07.740362Z","steps":["trace[356991712] 'agreement among raft nodes before linearized reading' (duration: 132.305928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T03:18:07.741532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.293318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila-server-0.18bb4723d06dfd44\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:47581 ","response":"range_response_count:343 size:258815"} {"level":"info","ts":"2026-06-22T03:18:07.742348Z","caller":"traceutil/trace.go:171","msg":"trace[2101661546] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila-server-0.18bb4723d06dfd44\u0000; range_end:/registry/events/openstack0; response_count:343; response_revision:47583; }","duration":"150.176076ms","start":"2026-06-22T03:18:07.592143Z","end":"2026-06-22T03:18:07.742319Z","steps":["trace[2101661546] 'agreement among raft nodes before linearized reading' (duration: 147.228012ms)"],"step_count":1} {"level":"info","ts":"2026-06-22T03:18:36.108967Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-22T03:18:36.115071Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005} {"level":"info","ts":"2026-06-22T03:18:36.115245Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005} {"level":"info","ts":"2026-06-22T03:18:43.793226Z","caller":"traceutil/trace.go:171","msg":"trace[183874022] linearizableReadLoop","detail":"{readStateIndex:50075; appliedIndex:50074; }","duration":"103.842299ms","start":"2026-06-22T03:18:43.68936Z","end":"2026-06-22T03:18:43.793202Z","steps":["trace[183874022] 'read index received' (duration: 100.984957ms)","trace[183874022] 'applied index is now lower than readState.Index' (duration: 2.856622ms)"],"step_count":2} {"level":"info","ts":"2026-06-22T03:18:43.793321Z","caller":"traceutil/trace.go:171","msg":"trace[1632187016] transaction","detail":"{read_only:false; response_revision:47896; number_of_response:1; }","duration":"113.58959ms","start":"2026-06-22T03:18:43.679659Z","end":"2026-06-22T03:18:43.793249Z","steps":["trace[1632187016] 'process raft request' (duration: 110.624066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-22T03:18:43.793654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.261858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/rabbitmq-barbican-server\" ","response":"range_response_count:1 size:908"} {"level":"info","ts":"2026-06-22T03:18:43.793772Z","caller":"traceutil/trace.go:171","msg":"trace[2008918865] range","detail":"{range_begin:/registry/rolebindings/openstack/rabbitmq-barbican-server; range_end:; response_count:1; response_revision:47896; }","duration":"104.413881ms","start":"2026-06-22T03:18:43.689324Z","end":"2026-06-22T03:18:43.793738Z","steps":["trace[2008918865] 'agreement among raft nodes before linearized reading' (duration: 104.015502ms)"],"step_count":1}