{"level":"warn","ts":"2026-07-15T21:47:09.082906Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T21:47:09.083087Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235: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.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-07-15T21:47:09.083178Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T21:47:09.083201Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-15T21:47:09.083241Z","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-07-15T21:47:09.083921Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-07-15T21:47:09.084028Z","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.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-07-15T21:47:09.088041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.774961ms"} {"level":"info","ts":"2026-07-15T21:47:09.094808Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-07-15T21:47:09.09501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T21:47:09.095056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-07-15T21:47:09.095094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T21:47:09.095125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-07-15T21:47:09.095187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-07-15T21:47:09.102701Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T21:47:09.104268Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T21:47:09.105342Z","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-07-15T21:47:09.107586Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T21:47:09.107912Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T21:47:09.108454Z","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-07-15T21:47:09.108512Z","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-07-15T21:47:09.111112Z","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-07-15T21:47:09.113596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-07-15T21:47:09.113837Z","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-07-15T21:47:09.113949Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-15T21:47:09.113995Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-07-15T21:47:09.114199Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T21:47:09.114249Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T21:47:09.11453Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-07-15T21:47:10.095441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T21:47:10.095539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T21:47:10.095595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-07-15T21:47:10.095634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-07-15T21:47:10.095648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-15T21:47:10.095669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-07-15T21:47:10.095689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-07-15T21:47:10.096911Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T21:47:10.096913Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T21:47:10.096908Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:47:10.097256Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T21:47:10.097373Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T21:47:10.097523Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T21:47:10.098276Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:47:10.098434Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:47:10.098501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:47:10.101102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-07-15T21:47:10.101384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T21:47:52.786111Z","caller":"traceutil/trace.go:171","msg":"trace[1628724886] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"210.911465ms","start":"2026-07-15T21:47:52.57514Z","end":"2026-07-15T21:47:52.786052Z","steps":["trace[1628724886] 'process raft request' (duration: 125.327422ms)","trace[1628724886] 'compare' (duration: 85.431371ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:47:53.271584Z","caller":"traceutil/trace.go:171","msg":"trace[1669361565] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"382.663193ms","start":"2026-07-15T21:47:52.888887Z","end":"2026-07-15T21:47:53.271551Z","steps":["trace[1669361565] 'read index received' (duration: 381.825058ms)","trace[1669361565] 'applied index is now lower than readState.Index' (duration: 836.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:47:53.271683Z","caller":"traceutil/trace.go:171","msg":"trace[358716471] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"425.199556ms","start":"2026-07-15T21:47:52.846429Z","end":"2026-07-15T21:47:53.271628Z","steps":["trace[358716471] 'process raft request' (duration: 424.518713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:47:53.271817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.899238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:47:53.27206Z","caller":"traceutil/trace.go:171","msg":"trace[1432032573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"383.204164ms","start":"2026-07-15T21:47:52.888828Z","end":"2026-07-15T21:47:53.272032Z","steps":["trace[1432032573] 'agreement among raft nodes before linearized reading' (duration: 382.892368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:47:53.272611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:47:52.888804Z","time spent":"383.299767ms","remote":"127.0.0.1:44682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T21:47:53.272798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:47:52.846391Z","time spent":"425.394189ms","remote":"127.0.0.1:44776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:49:21.158384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.314679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bb7bc735-cf37-4c08-9dc4-90eb21ce1741\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-15T21:49:21.158424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.546135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-15T21:49:21.15851Z","caller":"traceutil/trace.go:171","msg":"trace[563794674] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bb7bc735-cf37-4c08-9dc4-90eb21ce1741; range_end:; response_count:1; response_revision:1856; }","duration":"247.468612ms","start":"2026-07-15T21:49:20.911011Z","end":"2026-07-15T21:49:21.158479Z","steps":["trace[563794674] 'range keys from in-memory index tree' (duration: 247.094095ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:49:21.158531Z","caller":"traceutil/trace.go:171","msg":"trace[2087524834] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1856; }","duration":"199.726688ms","start":"2026-07-15T21:49:20.958776Z","end":"2026-07-15T21:49:21.158502Z","steps":["trace[2087524834] 'range keys from in-memory index tree' (duration: 199.300981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:49:21.650885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.168338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-07-15T21:49:21.65098Z","caller":"traceutil/trace.go:171","msg":"trace[2015439323] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1858; }","duration":"131.352932ms","start":"2026-07-15T21:49:21.519604Z","end":"2026-07-15T21:49:21.650956Z","steps":["trace[2015439323] 'range keys from in-memory index tree' (duration: 130.939934ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:50:11.977374Z","caller":"traceutil/trace.go:171","msg":"trace[1039976381] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"124.844607ms","start":"2026-07-15T21:50:11.852493Z","end":"2026-07-15T21:50:11.977338Z","steps":["trace[1039976381] 'process raft request' (duration: 124.594453ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:50:12.245412Z","caller":"traceutil/trace.go:171","msg":"trace[1641701984] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"246.084196ms","start":"2026-07-15T21:50:11.999291Z","end":"2026-07-15T21:50:12.245375Z","steps":["trace[1641701984] 'process raft request' (duration: 245.924293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:51:29.506266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.443377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-15T21:51:29.506466Z","caller":"traceutil/trace.go:171","msg":"trace[1488985987] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2648; }","duration":"104.674942ms","start":"2026-07-15T21:51:29.401763Z","end":"2026-07-15T21:51:29.506438Z","steps":["trace[1488985987] 'range keys from in-memory index tree' (duration: 104.182932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:51:35.075009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.651057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T21:51:35.075158Z","caller":"traceutil/trace.go:171","msg":"trace[593188226] linearizableReadLoop","detail":"{readStateIndex:2774; appliedIndex:2773; }","duration":"185.577331ms","start":"2026-07-15T21:51:34.889559Z","end":"2026-07-15T21:51:35.075136Z","steps":["trace[593188226] 'read index received' (duration: 35.022287ms)","trace[593188226] 'applied index is now lower than readState.Index' (duration: 150.549843ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:51:35.075255Z","caller":"traceutil/trace.go:171","msg":"trace[250223337] transaction","detail":"{read_only:false; response_revision:2675; number_of_response:1; }","duration":"246.633493ms","start":"2026-07-15T21:51:34.828572Z","end":"2026-07-15T21:51:35.075205Z","steps":["trace[250223337] 'process raft request' (duration: 96.058248ms)","trace[250223337] 'compare' (duration: 149.518915ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:51:35.075311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.739244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:51:35.075394Z","caller":"traceutil/trace.go:171","msg":"trace[1660363972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2675; }","duration":"185.880327ms","start":"2026-07-15T21:51:34.889489Z","end":"2026-07-15T21:51:35.075369Z","steps":["trace[1660363972] 'agreement among raft nodes before linearized reading' (duration: 185.734004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:51:35.075424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.133189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-07-15T21:51:35.075514Z","caller":"traceutil/trace.go:171","msg":"trace[62679901] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2675; }","duration":"116.271213ms","start":"2026-07-15T21:51:34.959219Z","end":"2026-07-15T21:51:35.07549Z","steps":["trace[62679901] 'agreement among raft nodes before linearized reading' (duration: 116.053738ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:51:35.265516Z","caller":"traceutil/trace.go:171","msg":"trace[1157425889] transaction","detail":"{read_only:false; response_revision:2676; number_of_response:1; }","duration":"186.419306ms","start":"2026-07-15T21:51:35.079057Z","end":"2026-07-15T21:51:35.265476Z","steps":["trace[1157425889] 'process raft request' (duration: 179.396043ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:51:35.269657Z","caller":"traceutil/trace.go:171","msg":"trace[359424427] linearizableReadLoop","detail":"{readStateIndex:2778; appliedIndex:2774; }","duration":"188.556208ms","start":"2026-07-15T21:51:35.081071Z","end":"2026-07-15T21:51:35.269628Z","steps":["trace[359424427] 'read index received' (duration: 177.394486ms)","trace[359424427] 'applied index is now lower than readState.Index' (duration: 11.160652ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:51:35.270188Z","caller":"traceutil/trace.go:171","msg":"trace[2066535865] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"189.67722ms","start":"2026-07-15T21:51:35.080467Z","end":"2026-07-15T21:51:35.270144Z","steps":["trace[2066535865] 'process raft request' (duration: 189.119159ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:51:35.270514Z","caller":"traceutil/trace.go:171","msg":"trace[1591154769] transaction","detail":"{read_only:false; response_revision:2677; number_of_response:1; }","duration":"191.362051ms","start":"2026-07-15T21:51:35.079134Z","end":"2026-07-15T21:51:35.270496Z","steps":["trace[1591154769] 'process raft request' (duration: 190.2304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:51:35.27051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.401074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"warn","ts":"2026-07-15T21:51:35.270509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.206346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:51:35.270672Z","caller":"traceutil/trace.go:171","msg":"trace[725956664] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"191.013464ms","start":"2026-07-15T21:51:35.079645Z","end":"2026-07-15T21:51:35.270658Z","steps":["trace[725956664] 'process raft request' (duration: 189.892443ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:51:35.270692Z","caller":"traceutil/trace.go:171","msg":"trace[1879389425] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2679; }","duration":"101.4285ms","start":"2026-07-15T21:51:35.169201Z","end":"2026-07-15T21:51:35.270629Z","steps":["trace[1879389425] 'agreement among raft nodes before linearized reading' (duration: 101.132014ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:51:35.270566Z","caller":"traceutil/trace.go:171","msg":"trace[1756287000] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2679; }","duration":"189.503805ms","start":"2026-07-15T21:51:35.081049Z","end":"2026-07-15T21:51:35.270553Z","steps":["trace[1756287000] 'agreement among raft nodes before linearized reading' (duration: 189.23377ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:54:31.947669Z","caller":"traceutil/trace.go:171","msg":"trace[560824241] transaction","detail":"{read_only:false; response_revision:3790; number_of_response:1; }","duration":"210.587439ms","start":"2026-07-15T21:54:31.737033Z","end":"2026-07-15T21:54:31.94762Z","steps":["trace[560824241] 'process raft request' (duration: 210.172831ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:55:51.186004Z","caller":"traceutil/trace.go:171","msg":"trace[884298868] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"161.161293ms","start":"2026-07-15T21:55:51.024802Z","end":"2026-07-15T21:55:51.185963Z","steps":["trace[884298868] 'process raft request' (duration: 158.652595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:55:56.311353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.746151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T21:55:56.311488Z","caller":"traceutil/trace.go:171","msg":"trace[1798541615] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5140; }","duration":"177.255582ms","start":"2026-07-15T21:55:56.13421Z","end":"2026-07-15T21:55:56.311466Z","steps":["trace[1798541615] 'read index received' (duration: 31.92µs)","trace[1798541615] 'applied index is now lower than readState.Index' (duration: 177.222442ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:55:56.311562Z","caller":"traceutil/trace.go:171","msg":"trace[1159494635] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"305.088847ms","start":"2026-07-15T21:55:56.006422Z","end":"2026-07-15T21:55:56.31151Z","steps":["trace[1159494635] 'process raft request' (duration: 127.083211ms)","trace[1159494635] 'compare' (duration: 177.557517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:55:56.311751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.543558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dxztz\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-15T21:55:56.311798Z","caller":"traceutil/trace.go:171","msg":"trace[334913263] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dxztz; range_end:; response_count:1; response_revision:4953; }","duration":"177.635269ms","start":"2026-07-15T21:55:56.134151Z","end":"2026-07-15T21:55:56.311786Z","steps":["trace[334913263] 'agreement among raft nodes before linearized reading' (duration: 177.403355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:55:56.312468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:55:56.006407Z","time spent":"305.253621ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1147,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:55:56.338997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.688459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2f3f799-7da1-4f3a-83dd-4fd78cfe2f4b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-15T21:55:56.339059Z","caller":"traceutil/trace.go:171","msg":"trace[1772600939] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2f3f799-7da1-4f3a-83dd-4fd78cfe2f4b; range_end:; response_count:1; response_revision:4958; }","duration":"178.806791ms","start":"2026-07-15T21:55:56.160239Z","end":"2026-07-15T21:55:56.339045Z","steps":["trace[1772600939] 'agreement among raft nodes before linearized reading' (duration: 178.566645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:56:11.19943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.377456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T21:56:11.199747Z","caller":"traceutil/trace.go:171","msg":"trace[2058514548] linearizableReadLoop","detail":"{readStateIndex:5435; appliedIndex:5432; }","duration":"310.28264ms","start":"2026-07-15T21:56:10.889444Z","end":"2026-07-15T21:56:11.199727Z","steps":["trace[2058514548] 'read index received' (duration: 121.497846ms)","trace[2058514548] 'applied index is now lower than readState.Index' (duration: 188.783174ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:56:11.19982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.382322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:56:11.199852Z","caller":"traceutil/trace.go:171","msg":"trace[1428618779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5238; }","duration":"310.442544ms","start":"2026-07-15T21:56:10.8894Z","end":"2026-07-15T21:56:11.199842Z","steps":["trace[1428618779] 'agreement among raft nodes before linearized reading' (duration: 310.388173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:56:11.199893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:56:10.889381Z","time spent":"310.501474ms","remote":"127.0.0.1:44686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-15T21:56:11.199915Z","caller":"traceutil/trace.go:171","msg":"trace[381565928] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"442.199406ms","start":"2026-07-15T21:56:10.757656Z","end":"2026-07-15T21:56:11.199855Z","steps":["trace[381565928] 'process raft request' (duration: 253.259329ms)","trace[381565928] 'compare' (duration: 188.06084ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:56:11.200105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:56:10.757618Z","time spent":"442.38432ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:56:11.200198Z","caller":"traceutil/trace.go:171","msg":"trace[76292977] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"441.645366ms","start":"2026-07-15T21:56:10.758539Z","end":"2026-07-15T21:56:11.200184Z","steps":["trace[76292977] 'process raft request' (duration: 441.040464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:56:11.20028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:56:10.758512Z","time spent":"441.731928ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:56:11.200295Z","caller":"traceutil/trace.go:171","msg":"trace[1309971214] transaction","detail":"{read_only:false; response_revision:5238; number_of_response:1; }","duration":"440.420763ms","start":"2026-07-15T21:56:10.759822Z","end":"2026-07-15T21:56:11.200243Z","steps":["trace[1309971214] 'process raft request' (duration: 439.854571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:56:11.200507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:56:10.759804Z","time spent":"440.590676ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:56:18.180516Z","caller":"traceutil/trace.go:171","msg":"trace[1117471393] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"101.086485ms","start":"2026-07-15T21:56:18.079376Z","end":"2026-07-15T21:56:18.180463Z","steps":["trace[1117471393] 'process raft request' (duration: 100.748589ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:56:18.18057Z","caller":"traceutil/trace.go:171","msg":"trace[1155792698] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"101.049654ms","start":"2026-07-15T21:56:18.07946Z","end":"2026-07-15T21:56:18.180509Z","steps":["trace[1155792698] 'process raft request' (duration: 100.866631ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:56:18.180944Z","caller":"traceutil/trace.go:171","msg":"trace[1267060015] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"101.33049ms","start":"2026-07-15T21:56:18.079573Z","end":"2026-07-15T21:56:18.180903Z","steps":["trace[1267060015] 'process raft request' (duration: 100.82416ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:56:18.182989Z","caller":"traceutil/trace.go:171","msg":"trace[776695464] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"101.165506ms","start":"2026-07-15T21:56:18.08179Z","end":"2026-07-15T21:56:18.182955Z","steps":["trace[776695464] 'process raft request' (duration: 100.626426ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:56:18.182609Z","caller":"traceutil/trace.go:171","msg":"trace[668130762] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"101.285629ms","start":"2026-07-15T21:56:18.081287Z","end":"2026-07-15T21:56:18.182573Z","steps":["trace[668130762] 'process raft request' (duration: 99.490674ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:57:10.135464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2861} {"level":"info","ts":"2026-07-15T21:57:10.206263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2861,"took":"68.289208ms","hash":317989484,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":32907264,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T21:57:10.206343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":317989484,"revision":2861,"compact-revision":-1} {"level":"info","ts":"2026-07-15T22:02:10.14105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5907} {"level":"info","ts":"2026-07-15T22:02:10.225135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5907,"took":"81.498029ms","hash":2047013206,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":27168768,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T22:02:10.225217Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2047013206,"revision":5907,"compact-revision":2861} {"level":"info","ts":"2026-07-15T22:02:12.336358Z","caller":"traceutil/trace.go:171","msg":"trace[983139574] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"116.927636ms","start":"2026-07-15T22:02:12.219399Z","end":"2026-07-15T22:02:12.336326Z","steps":["trace[983139574] 'process raft request' (duration: 116.777364ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:02:25.027575Z","caller":"traceutil/trace.go:171","msg":"trace[206060027] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"167.610166ms","start":"2026-07-15T22:02:24.859909Z","end":"2026-07-15T22:02:25.027519Z","steps":["trace[206060027] 'process raft request' (duration: 106.920406ms)","trace[206060027] 'compare' (duration: 60.477986ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:02:53.159774Z","caller":"traceutil/trace.go:171","msg":"trace[1764367459] transaction","detail":"{read_only:false; response_revision:8422; number_of_response:1; }","duration":"133.503264ms","start":"2026-07-15T22:02:53.026232Z","end":"2026-07-15T22:02:53.159735Z","steps":["trace[1764367459] 'process raft request' (duration: 125.241576ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:03:05.969791Z","caller":"traceutil/trace.go:171","msg":"trace[1293835481] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"103.848206ms","start":"2026-07-15T22:03:05.865897Z","end":"2026-07-15T22:03:05.969745Z","steps":["trace[1293835481] 'process raft request' (duration: 103.509799ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:03:06.731901Z","caller":"traceutil/trace.go:171","msg":"trace[200484975] linearizableReadLoop","detail":"{readStateIndex:8846; appliedIndex:8844; }","duration":"115.314485ms","start":"2026-07-15T22:03:06.61656Z","end":"2026-07-15T22:03:06.731875Z","steps":["trace[200484975] 'read index received' (duration: 21.95944ms)","trace[200484975] 'applied index is now lower than readState.Index' (duration: 93.353735ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:03:06.732019Z","caller":"traceutil/trace.go:171","msg":"trace[832185652] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"121.640827ms","start":"2026-07-15T22:03:06.610324Z","end":"2026-07-15T22:03:06.731965Z","steps":["trace[832185652] 'process raft request' (duration: 121.34634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:03:06.732355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.597241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T22:03:06.732526Z","caller":"traceutil/trace.go:171","msg":"trace[279324199] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8542; }","duration":"116.001169ms","start":"2026-07-15T22:03:06.616496Z","end":"2026-07-15T22:03:06.732497Z","steps":["trace[279324199] 'agreement among raft nodes before linearized reading' (duration: 115.585451ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:03:52.974798Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002473.wal"} {"level":"info","ts":"2026-07-15T22:05:08.452274Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T22:05:08.458956Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-15T22:05:08.459038Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-15T22:07:10.150105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8145} {"level":"info","ts":"2026-07-15T22:07:10.238485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8145,"took":"85.118296ms","hash":2286006727,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":25747456,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-15T22:07:10.238561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2286006727,"revision":8145,"compact-revision":5907} {"level":"info","ts":"2026-07-15T22:07:59.070596Z","caller":"traceutil/trace.go:171","msg":"trace[472601330] linearizableReadLoop","detail":"{readStateIndex:11510; appliedIndex:11509; }","duration":"156.414128ms","start":"2026-07-15T22:07:58.914149Z","end":"2026-07-15T22:07:59.070563Z","steps":["trace[472601330] 'read index received' (duration: 155.115895ms)","trace[472601330] 'applied index is now lower than readState.Index' (duration: 1.296703ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:07:59.070666Z","caller":"traceutil/trace.go:171","msg":"trace[1350400493] transaction","detail":"{read_only:false; response_revision:11130; number_of_response:1; }","duration":"175.662068ms","start":"2026-07-15T22:07:58.894957Z","end":"2026-07-15T22:07:59.070619Z","steps":["trace[1350400493] 'process raft request' (duration: 174.724301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:07:59.07091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.149657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-07-15T22:07:59.070914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.747665ms","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-07-15T22:07:59.070967Z","caller":"traceutil/trace.go:171","msg":"trace[1685476441] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11130; }","duration":"149.27471ms","start":"2026-07-15T22:07:58.921676Z","end":"2026-07-15T22:07:59.07095Z","steps":["trace[1685476441] 'agreement among raft nodes before linearized reading' (duration: 149.090776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:07:59.071084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.40807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T22:07:59.071174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.46711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T22:07:59.071131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.45616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T22:07:59.071213Z","caller":"traceutil/trace.go:171","msg":"trace[1460732998] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11130; }","duration":"124.559081ms","start":"2026-07-15T22:07:58.946644Z","end":"2026-07-15T22:07:59.071203Z","steps":["trace[1460732998] 'agreement among raft nodes before linearized reading' (duration: 124.50181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:07:59.071116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.183415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T22:07:59.071021Z","caller":"traceutil/trace.go:171","msg":"trace[965534060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11130; }","duration":"156.905688ms","start":"2026-07-15T22:07:58.914085Z","end":"2026-07-15T22:07:59.070991Z","steps":["trace[965534060] 'agreement among raft nodes before linearized reading' (duration: 156.616841ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:07:59.072082Z","caller":"traceutil/trace.go:171","msg":"trace[1388737936] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11130; }","duration":"123.587123ms","start":"2026-07-15T22:07:58.947602Z","end":"2026-07-15T22:07:59.071189Z","steps":["trace[1388737936] 'agreement among raft nodes before linearized reading' (duration: 123.342349ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:07:59.072748Z","caller":"traceutil/trace.go:171","msg":"trace[1007501633] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11130; }","duration":"124.423419ms","start":"2026-07-15T22:07:58.94686Z","end":"2026-07-15T22:07:59.071284Z","steps":["trace[1007501633] 'agreement among raft nodes before linearized reading' (duration: 124.133044ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:07:59.072865Z","caller":"traceutil/trace.go:171","msg":"trace[2027431561] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11130; }","duration":"123.592273ms","start":"2026-07-15T22:07:58.94763Z","end":"2026-07-15T22:07:59.071222Z","steps":["trace[2027431561] 'agreement among raft nodes before linearized reading' (duration: 123.367229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:07:59.073036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.467778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-15T22:07:59.073129Z","caller":"traceutil/trace.go:171","msg":"trace[501210382] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11130; }","duration":"125.537039ms","start":"2026-07-15T22:07:58.947551Z","end":"2026-07-15T22:07:59.073088Z","steps":["trace[501210382] 'agreement among raft nodes before linearized reading' (duration: 123.649955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:07:59.074459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.061138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-07-15T22:07:59.074535Z","caller":"traceutil/trace.go:171","msg":"trace[585958175] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11130; }","duration":"126.15121ms","start":"2026-07-15T22:07:58.948352Z","end":"2026-07-15T22:07:59.074503Z","steps":["trace[585958175] 'agreement among raft nodes before linearized reading' (duration: 122.977083ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:12:10.156909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10667} {"level":"info","ts":"2026-07-15T22:12:10.237986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10667,"took":"77.73396ms","hash":3534874042,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":27283456,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T22:12:10.238114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3534874042,"revision":10667,"compact-revision":8145} {"level":"info","ts":"2026-07-15T22:14:11.560073Z","caller":"traceutil/trace.go:171","msg":"trace[923411273] linearizableReadLoop","detail":"{readStateIndex:14115; appliedIndex:14114; }","duration":"144.41927ms","start":"2026-07-15T22:14:11.415626Z","end":"2026-07-15T22:14:11.560045Z","steps":["trace[923411273] 'read index received' (duration: 144.180655ms)","trace[923411273] 'applied index is now lower than readState.Index' (duration: 237.375µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:14:11.560178Z","caller":"traceutil/trace.go:171","msg":"trace[1551252484] transaction","detail":"{read_only:false; response_revision:13648; number_of_response:1; }","duration":"149.148091ms","start":"2026-07-15T22:14:11.410965Z","end":"2026-07-15T22:14:11.560113Z","steps":["trace[1551252484] 'process raft request' (duration: 148.917817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:14:11.560353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.714806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T22:14:11.560394Z","caller":"traceutil/trace.go:171","msg":"trace[1904508041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13648; }","duration":"144.823648ms","start":"2026-07-15T22:14:11.415558Z","end":"2026-07-15T22:14:11.560382Z","steps":["trace[1904508041] 'agreement among raft nodes before linearized reading' (duration: 144.661815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:14:11.560457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.508016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-15T22:14:11.560557Z","caller":"traceutil/trace.go:171","msg":"trace[246220372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13648; }","duration":"128.649019ms","start":"2026-07-15T22:14:11.431887Z","end":"2026-07-15T22:14:11.560536Z","steps":["trace[246220372] 'agreement among raft nodes before linearized reading' (duration: 128.389264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:14:36.681133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.761021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T22:14:36.681256Z","caller":"traceutil/trace.go:171","msg":"trace[1082224449] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:13906; }","duration":"195.956465ms","start":"2026-07-15T22:14:36.485275Z","end":"2026-07-15T22:14:36.681231Z","steps":["trace[1082224449] 'count revisions from in-memory index tree' (duration: 195.626479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.842462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.163261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T22:15:57.842736Z","caller":"traceutil/trace.go:171","msg":"trace[1703786717] linearizableReadLoop","detail":"{readStateIndex:15401; appliedIndex:15400; }","duration":"557.052815ms","start":"2026-07-15T22:15:57.28566Z","end":"2026-07-15T22:15:57.842713Z","steps":["trace[1703786717] 'read index received' (duration: 4.447246ms)","trace[1703786717] 'applied index is now lower than readState.Index' (duration: 552.603739ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:15:57.842938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.266589ms","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-07-15T22:15:57.84291Z","caller":"traceutil/trace.go:171","msg":"trace[409555025] transaction","detail":"{read_only:false; response_revision:14896; number_of_response:1; }","duration":"634.126517ms","start":"2026-07-15T22:15:57.208696Z","end":"2026-07-15T22:15:57.842822Z","steps":["trace[409555025] 'process raft request' (duration: 81.469767ms)","trace[409555025] 'compare' (duration: 551.947436ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:15:57.843008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.403046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T22:15:57.84301Z","caller":"traceutil/trace.go:171","msg":"trace[1685963631] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14896; }","duration":"557.389012ms","start":"2026-07-15T22:15:57.285602Z","end":"2026-07-15T22:15:57.842991Z","steps":["trace[1685963631] 'agreement among raft nodes before linearized reading' (duration: 557.224959ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.843056Z","caller":"traceutil/trace.go:171","msg":"trace[603788879] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14896; }","duration":"198.501528ms","start":"2026-07-15T22:15:57.644544Z","end":"2026-07-15T22:15:57.843045Z","steps":["trace[603788879] 'agreement among raft nodes before linearized reading' (duration: 198.423426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.843042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.267479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T22:15:57.843176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.208663Z","time spent":"634.373232ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:15:57.843184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.327801ms","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-07-15T22:15:57.843246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.398566ms","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-07-15T22:15:57.843288Z","caller":"traceutil/trace.go:171","msg":"trace[1067920356] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14896; }","duration":"426.450274ms","start":"2026-07-15T22:15:57.416807Z","end":"2026-07-15T22:15:57.843257Z","steps":["trace[1067920356] 'agreement among raft nodes before linearized reading' (duration: 426.137257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.843248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.524328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lnzjx\" ","response":"range_response_count:1 size:20652"} {"level":"warn","ts":"2026-07-15T22:15:57.843338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.416802Z","time spent":"426.527205ms","remote":"127.0.0.1:44854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-07-15T22:15:57.843362Z","caller":"traceutil/trace.go:171","msg":"trace[1957962786] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14896; }","duration":"198.509749ms","start":"2026-07-15T22:15:57.644815Z","end":"2026-07-15T22:15:57.843325Z","steps":["trace[1957962786] 'agreement among raft nodes before linearized reading' (duration: 198.365885ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.84338Z","caller":"traceutil/trace.go:171","msg":"trace[1938066985] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lnzjx; range_end:; response_count:1; response_revision:14896; }","duration":"237.710712ms","start":"2026-07-15T22:15:57.60563Z","end":"2026-07-15T22:15:57.843341Z","steps":["trace[1938066985] 'agreement among raft nodes before linearized reading' (duration: 237.363275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.843413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.832397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-07-15T22:15:57.843422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.807885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-15T22:15:57.843657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.802281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-15T22:15:57.843661Z","caller":"traceutil/trace.go:171","msg":"trace[1191597123] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14896; }","duration":"198.918027ms","start":"2026-07-15T22:15:57.644543Z","end":"2026-07-15T22:15:57.843461Z","steps":["trace[1191597123] 'agreement among raft nodes before linearized reading' (duration: 198.781374ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.843661Z","caller":"traceutil/trace.go:171","msg":"trace[630246346] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14896; }","duration":"339.886618ms","start":"2026-07-15T22:15:57.503563Z","end":"2026-07-15T22:15:57.84345Z","steps":["trace[630246346] 'agreement among raft nodes before linearized reading' (duration: 339.745926ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.843694Z","caller":"traceutil/trace.go:171","msg":"trace[1868549609] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14896; }","duration":"427.038015ms","start":"2026-07-15T22:15:57.416646Z","end":"2026-07-15T22:15:57.843684Z","steps":["trace[1868549609] 'agreement among raft nodes before linearized reading' (duration: 426.75623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.843713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.503486Z","time spent":"340.214125ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-15T22:15:57.843708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.086747ms","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-07-15T22:15:57.843272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.713446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-9g9vs\" ","response":"range_response_count:1 size:9796"} {"level":"warn","ts":"2026-07-15T22:15:57.843771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.481463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T22:15:57.84373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.416639Z","time spent":"427.083576ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-15T22:15:57.843792Z","caller":"traceutil/trace.go:171","msg":"trace[1241325330] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-9g9vs; range_end:; response_count:1; response_revision:14896; }","duration":"260.277057ms","start":"2026-07-15T22:15:57.583502Z","end":"2026-07-15T22:15:57.843779Z","steps":["trace[1241325330] 'agreement among raft nodes before linearized reading' (duration: 259.624853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.843381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.406913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-15T22:15:57.843855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.60977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T22:15:57.843876Z","caller":"traceutil/trace.go:171","msg":"trace[1335414783] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14896; }","duration":"544.935912ms","start":"2026-07-15T22:15:57.298926Z","end":"2026-07-15T22:15:57.843862Z","steps":["trace[1335414783] 'agreement among raft nodes before linearized reading' (duration: 544.389602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.843882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.398492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T22:15:57.843256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.676011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T22:15:57.843903Z","caller":"traceutil/trace.go:171","msg":"trace[642672738] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:14896; }","duration":"486.707422ms","start":"2026-07-15T22:15:57.357182Z","end":"2026-07-15T22:15:57.843889Z","steps":["trace[642672738] 'agreement among raft nodes before linearized reading' (duration: 486.62368ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.843918Z","caller":"traceutil/trace.go:171","msg":"trace[47078138] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:14896; }","duration":"544.469734ms","start":"2026-07-15T22:15:57.299438Z","end":"2026-07-15T22:15:57.843908Z","steps":["trace[47078138] 'agreement among raft nodes before linearized reading' (duration: 544.410133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.843921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.298901Z","time spent":"545.003544ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-15T22:15:57.843773Z","caller":"traceutil/trace.go:171","msg":"trace[1918435689] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14896; }","duration":"555.184759ms","start":"2026-07-15T22:15:57.288569Z","end":"2026-07-15T22:15:57.843754Z","steps":["trace[1918435689] 'agreement among raft nodes before linearized reading' (duration: 554.804932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.84394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.357153Z","time spent":"486.778334ms","remote":"127.0.0.1:58916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:15:57.843949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.416398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-15T22:15:57.84395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.299422Z","time spent":"544.520395ms","remote":"127.0.0.1:43398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:15:57.843956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.463005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T22:15:57.843982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.186796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T22:15:57.843985Z","caller":"traceutil/trace.go:171","msg":"trace[1844532106] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14896; }","duration":"550.472419ms","start":"2026-07-15T22:15:57.293504Z","end":"2026-07-15T22:15:57.843976Z","steps":["trace[1844532106] 'agreement among raft nodes before linearized reading' (duration: 550.372858ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.843928Z","caller":"traceutil/trace.go:171","msg":"trace[43470050] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14896; }","duration":"199.415126ms","start":"2026-07-15T22:15:57.644503Z","end":"2026-07-15T22:15:57.843918Z","steps":["trace[43470050] 'agreement among raft nodes before linearized reading' (duration: 198.655051ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.844021Z","caller":"traceutil/trace.go:171","msg":"trace[2052376747] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14896; }","duration":"558.29289ms","start":"2026-07-15T22:15:57.285719Z","end":"2026-07-15T22:15:57.844012Z","steps":["trace[2052376747] 'agreement among raft nodes before linearized reading' (duration: 558.233279ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:15:57.844Z","caller":"traceutil/trace.go:171","msg":"trace[1971696537] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14896; }","duration":"555.538617ms","start":"2026-07-15T22:15:57.288451Z","end":"2026-07-15T22:15:57.84399Z","steps":["trace[1971696537] 'agreement among raft nodes before linearized reading' (duration: 555.474775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.844067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.288436Z","time spent":"555.623998ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-15T22:15:57.844061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.285685Z","time spent":"558.367011ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-07-15T22:15:57.843826Z","caller":"traceutil/trace.go:171","msg":"trace[37238146] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14896; }","duration":"427.582565ms","start":"2026-07-15T22:15:57.416216Z","end":"2026-07-15T22:15:57.843799Z","steps":["trace[37238146] 'agreement among raft nodes before linearized reading' (duration: 427.534254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.844166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.416188Z","time spent":"427.969543ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-15T22:15:57.84403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.293493Z","time spent":"550.53109ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-15T22:15:57.843982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.288558Z","time spent":"555.413243ms","remote":"127.0.0.1:44854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":689,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-15T22:15:57.843076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.285579Z","time spent":"557.486843ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-07-15T22:15:57.844403Z","caller":"traceutil/trace.go:171","msg":"trace[547182197] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14896; }","duration":"557.401702ms","start":"2026-07-15T22:15:57.285734Z","end":"2026-07-15T22:15:57.843136Z","steps":["trace[547182197] 'agreement among raft nodes before linearized reading' (duration: 557.194868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:15:57.844456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:15:57.285663Z","time spent":"558.781539ms","remote":"127.0.0.1:44920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"info","ts":"2026-07-15T22:17:10.163676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12743} {"level":"info","ts":"2026-07-15T22:17:10.245802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12743,"took":"79.258044ms","hash":1719242466,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":29896704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T22:17:10.245867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1719242466,"revision":12743,"compact-revision":10667} {"level":"info","ts":"2026-07-15T22:21:54.028645Z","caller":"traceutil/trace.go:171","msg":"trace[1792960422] transaction","detail":"{read_only:false; response_revision:17531; number_of_response:1; }","duration":"161.743262ms","start":"2026-07-15T22:21:53.866862Z","end":"2026-07-15T22:21:54.028605Z","steps":["trace[1792960422] 'process raft request' (duration: 159.56611ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:21:54.028705Z","caller":"traceutil/trace.go:171","msg":"trace[160043584] linearizableReadLoop","detail":"{readStateIndex:18124; appliedIndex:18123; }","duration":"149.556248ms","start":"2026-07-15T22:21:53.879102Z","end":"2026-07-15T22:21:54.028659Z","steps":["trace[160043584] 'read index received' (duration: 147.359476ms)","trace[160043584] 'applied index is now lower than readState.Index' (duration: 2.193822ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:21:54.028775Z","caller":"traceutil/trace.go:171","msg":"trace[2036091282] transaction","detail":"{read_only:false; response_revision:17532; number_of_response:1; }","duration":"146.209652ms","start":"2026-07-15T22:21:53.882516Z","end":"2026-07-15T22:21:54.028726Z","steps":["trace[2036091282] 'process raft request' (duration: 146.047939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:21:54.028953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.828022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:21:54.029027Z","caller":"traceutil/trace.go:171","msg":"trace[11757948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17532; }","duration":"124.944724ms","start":"2026-07-15T22:21:53.904064Z","end":"2026-07-15T22:21:54.029009Z","steps":["trace[11757948] 'agreement among raft nodes before linearized reading' (duration: 124.810851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:21:54.029044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.909545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-wrt94\" ","response":"range_response_count:1 size:15746"} {"level":"info","ts":"2026-07-15T22:21:54.029148Z","caller":"traceutil/trace.go:171","msg":"trace[952114535] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-wrt94; range_end:; response_count:1; response_revision:17532; }","duration":"150.049187ms","start":"2026-07-15T22:21:53.879072Z","end":"2026-07-15T22:21:54.029121Z","steps":["trace[952114535] 'agreement among raft nodes before linearized reading' (duration: 149.68291ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:21:59.706543Z","caller":"traceutil/trace.go:171","msg":"trace[1178798059] transaction","detail":"{read_only:false; response_revision:17581; number_of_response:1; }","duration":"140.80658ms","start":"2026-07-15T22:21:59.565702Z","end":"2026-07-15T22:21:59.706509Z","steps":["trace[1178798059] 'process raft request' (duration: 140.597435ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:22:10.170252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15333} {"level":"info","ts":"2026-07-15T22:22:10.269871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15333,"took":"94.759873ms","hash":1306357511,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":32600064,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T22:22:10.269963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1306357511,"revision":15333,"compact-revision":12743} {"level":"info","ts":"2026-07-15T22:25:00.1211Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T22:25:00.129605Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-15T22:25:00.130577Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-15T22:27:10.180975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17852} {"level":"info","ts":"2026-07-15T22:27:10.300302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17852,"took":"115.486849ms","hash":3313825421,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":31404032,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T22:27:10.300389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3313825421,"revision":17852,"compact-revision":15333} {"level":"warn","ts":"2026-07-15T22:28:42.426681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.163825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:28:42.426861Z","caller":"traceutil/trace.go:171","msg":"trace[457480881] transaction","detail":"{read_only:false; response_revision:20957; number_of_response:1; }","duration":"164.326716ms","start":"2026-07-15T22:28:42.262477Z","end":"2026-07-15T22:28:42.426804Z","steps":["trace[457480881] 'process raft request' (duration: 37.937776ms)","trace[457480881] 'compare' (duration: 126.032313ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:28:42.427125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.440385ms","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-07-15T22:28:42.427173Z","caller":"traceutil/trace.go:171","msg":"trace[157439335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20957; }","duration":"108.540757ms","start":"2026-07-15T22:28:42.318621Z","end":"2026-07-15T22:28:42.427162Z","steps":["trace[157439335] 'agreement among raft nodes before linearized reading' (duration: 108.398705ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:28:42.426966Z","caller":"traceutil/trace.go:171","msg":"trace[1765266627] linearizableReadLoop","detail":"{readStateIndex:21656; appliedIndex:21655; }","duration":"108.093598ms","start":"2026-07-15T22:28:42.318693Z","end":"2026-07-15T22:28:42.426786Z","steps":["trace[1765266627] 'read index received' (duration: 74.491µs)","trace[1765266627] 'applied index is now lower than readState.Index' (duration: 108.017927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:28:42.489321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.173062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-15T22:28:42.489376Z","caller":"traceutil/trace.go:171","msg":"trace[1603532999] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20960; }","duration":"165.275924ms","start":"2026-07-15T22:28:42.324085Z","end":"2026-07-15T22:28:42.489361Z","steps":["trace[1603532999] 'agreement among raft nodes before linearized reading' (duration: 165.147731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:28:42.489455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.753545ms","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-07-15T22:28:42.489562Z","caller":"traceutil/trace.go:171","msg":"trace[407474400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20960; }","duration":"105.973699ms","start":"2026-07-15T22:28:42.383567Z","end":"2026-07-15T22:28:42.48954Z","steps":["trace[407474400] 'agreement among raft nodes before linearized reading' (duration: 105.640192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:28:42.489589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.384736ms","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-07-15T22:28:42.489723Z","caller":"traceutil/trace.go:171","msg":"trace[597830971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20960; }","duration":"106.530419ms","start":"2026-07-15T22:28:42.383154Z","end":"2026-07-15T22:28:42.489684Z","steps":["trace[597830971] 'agreement among raft nodes before linearized reading' (duration: 106.136862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:29:03.746391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.437653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:29:03.746518Z","caller":"traceutil/trace.go:171","msg":"trace[1774471148] linearizableReadLoop","detail":"{readStateIndex:21776; appliedIndex:21775; }","duration":"236.488358ms","start":"2026-07-15T22:29:03.510011Z","end":"2026-07-15T22:29:03.746499Z","steps":["trace[1774471148] 'read index received' (duration: 65.699018ms)","trace[1774471148] 'applied index is now lower than readState.Index' (duration: 170.78744ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:29:03.746648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.645022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:29:03.746684Z","caller":"traceutil/trace.go:171","msg":"trace[513099684] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:21071; }","duration":"236.761463ms","start":"2026-07-15T22:29:03.509912Z","end":"2026-07-15T22:29:03.746674Z","steps":["trace[513099684] 'agreement among raft nodes before linearized reading' (duration: 236.638991ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:29:03.7467Z","caller":"traceutil/trace.go:171","msg":"trace[177259670] transaction","detail":"{read_only:false; response_revision:21071; number_of_response:1; }","duration":"323.78044ms","start":"2026-07-15T22:29:03.422864Z","end":"2026-07-15T22:29:03.746645Z","steps":["trace[177259670] 'process raft request' (duration: 152.964799ms)","trace[177259670] 'compare' (duration: 169.940843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:29:03.746917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:29:03.422835Z","time spent":"323.969733ms","remote":"127.0.0.1:44710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:29:04.035498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.355072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:29:04.035588Z","caller":"traceutil/trace.go:171","msg":"trace[1149325722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21071; }","duration":"146.499824ms","start":"2026-07-15T22:29:03.889071Z","end":"2026-07-15T22:29:04.035571Z","steps":["trace[1149325722] 'range keys from in-memory index tree' (duration: 146.27432ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:29:04.575338Z","caller":"traceutil/trace.go:171","msg":"trace[859705395] transaction","detail":"{read_only:false; response_revision:21073; number_of_response:1; }","duration":"141.821075ms","start":"2026-07-15T22:29:04.433494Z","end":"2026-07-15T22:29:04.575315Z","steps":["trace[859705395] 'process raft request' (duration: 141.342325ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:29:04.759185Z","caller":"traceutil/trace.go:171","msg":"trace[893766420] linearizableReadLoop","detail":"{readStateIndex:21780; appliedIndex:21778; }","duration":"173.728066ms","start":"2026-07-15T22:29:04.585437Z","end":"2026-07-15T22:29:04.759165Z","steps":["trace[893766420] 'read index received' (duration: 13.178952ms)","trace[893766420] 'applied index is now lower than readState.Index' (duration: 160.548194ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:29:04.759333Z","caller":"traceutil/trace.go:171","msg":"trace[438753566] transaction","detail":"{read_only:false; response_revision:21075; number_of_response:1; }","duration":"180.671649ms","start":"2026-07-15T22:29:04.578611Z","end":"2026-07-15T22:29:04.759283Z","steps":["trace[438753566] 'process raft request' (duration: 159.744209ms)","trace[438753566] 'compare' (duration: 20.684556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:29:04.759568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.990506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-15T22:29:04.759592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.122333ms","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-07-15T22:29:04.759625Z","caller":"traceutil/trace.go:171","msg":"trace[1064181647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21075; }","duration":"156.096668ms","start":"2026-07-15T22:29:04.603516Z","end":"2026-07-15T22:29:04.759612Z","steps":["trace[1064181647] 'agreement among raft nodes before linearized reading' (duration: 155.906885ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:29:04.759643Z","caller":"traceutil/trace.go:171","msg":"trace[1389323877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21075; }","duration":"174.206766ms","start":"2026-07-15T22:29:04.585421Z","end":"2026-07-15T22:29:04.759627Z","steps":["trace[1389323877] 'agreement among raft nodes before linearized reading' (duration: 173.861569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:29:04.759781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.906637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-15T22:29:04.759813Z","caller":"traceutil/trace.go:171","msg":"trace[382977780] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:21075; }","duration":"153.954248ms","start":"2026-07-15T22:29:04.605848Z","end":"2026-07-15T22:29:04.759802Z","steps":["trace[382977780] 'agreement among raft nodes before linearized reading' (duration: 153.870966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:29:04.759754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.9167ms","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-07-15T22:29:04.759918Z","caller":"traceutil/trace.go:171","msg":"trace[1103628801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21075; }","duration":"150.111644ms","start":"2026-07-15T22:29:04.609798Z","end":"2026-07-15T22:29:04.759909Z","steps":["trace[1103628801] 'agreement among raft nodes before linearized reading' (duration: 149.875739ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:32:10.18894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20290} {"level":"info","ts":"2026-07-15T22:32:10.301204Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20290,"took":"108.878525ms","hash":3516920904,"current-db-size-bytes":57430016,"current-db-size":"57 MB","current-db-size-in-use-bytes":32174080,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T22:32:10.301287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3516920904,"revision":20290,"compact-revision":17852} {"level":"warn","ts":"2026-07-15T22:32:40.226469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.144378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-15T22:32:40.226578Z","caller":"traceutil/trace.go:171","msg":"trace[908949513] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23430; }","duration":"276.320091ms","start":"2026-07-15T22:32:39.950234Z","end":"2026-07-15T22:32:40.226554Z","steps":["trace[908949513] 'range keys from in-memory index tree' (duration: 275.942274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:32:40.226908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.269999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-15T22:32:40.226901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.154099ms","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-07-15T22:32:40.226941Z","caller":"traceutil/trace.go:171","msg":"trace[1040707876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23430; }","duration":"278.30289ms","start":"2026-07-15T22:32:39.948628Z","end":"2026-07-15T22:32:40.226931Z","steps":["trace[1040707876] 'range keys from in-memory index tree' (duration: 278.158526ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:32:40.227008Z","caller":"traceutil/trace.go:171","msg":"trace[215438234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23430; }","duration":"264.329463ms","start":"2026-07-15T22:32:39.962654Z","end":"2026-07-15T22:32:40.226983Z","steps":["trace[215438234] 'range keys from in-memory index tree' (duration: 263.893704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:32:40.227154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.30718ms","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-07-15T22:32:40.227129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.637328ms","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-07-15T22:32:40.22719Z","caller":"traceutil/trace.go:171","msg":"trace[1933967505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23430; }","duration":"278.355572ms","start":"2026-07-15T22:32:39.94882Z","end":"2026-07-15T22:32:40.227176Z","steps":["trace[1933967505] 'range keys from in-memory index tree' (duration: 278.233769ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:32:40.227231Z","caller":"traceutil/trace.go:171","msg":"trace[829974729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23430; }","duration":"277.76316ms","start":"2026-07-15T22:32:39.949443Z","end":"2026-07-15T22:32:40.227206Z","steps":["trace[829974729] 'range keys from in-memory index tree' (duration: 277.470625ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:32:40.2273Z","caller":"traceutil/trace.go:171","msg":"trace[638092695] linearizableReadLoop","detail":"{readStateIndex:24204; appliedIndex:24203; }","duration":"178.414857ms","start":"2026-07-15T22:32:40.048874Z","end":"2026-07-15T22:32:40.227289Z","steps":["trace[638092695] 'read index received' (duration: 176.199894ms)","trace[638092695] 'applied index is now lower than readState.Index' (duration: 2.213713ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:32:40.22739Z","caller":"traceutil/trace.go:171","msg":"trace[1573164946] transaction","detail":"{read_only:false; response_revision:23431; number_of_response:1; }","duration":"223.816877ms","start":"2026-07-15T22:32:40.003562Z","end":"2026-07-15T22:32:40.227379Z","steps":["trace[1573164946] 'process raft request' (duration: 221.583954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:32:40.227464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.5809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:32:40.227522Z","caller":"traceutil/trace.go:171","msg":"trace[127835924] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:23431; }","duration":"178.678212ms","start":"2026-07-15T22:32:40.048826Z","end":"2026-07-15T22:32:40.227504Z","steps":["trace[127835924] 'agreement among raft nodes before linearized reading' (duration: 178.58267ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:49.828508Z","caller":"traceutil/trace.go:171","msg":"trace[2131042593] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"106.272673ms","start":"2026-07-15T22:35:49.722185Z","end":"2026-07-15T22:35:49.828458Z","steps":["trace[2131042593] 'process raft request' (duration: 106.056679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:50.141212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.471848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:35:50.141322Z","caller":"traceutil/trace.go:171","msg":"trace[699232549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25452; }","duration":"252.60105ms","start":"2026-07-15T22:35:49.888673Z","end":"2026-07-15T22:35:50.141274Z","steps":["trace[699232549] 'range keys from in-memory index tree' (duration: 252.401297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:50.141296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.090391ms","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":"warn","ts":"2026-07-15T22:35:50.141296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.476258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:35:50.141393Z","caller":"traceutil/trace.go:171","msg":"trace[521503994] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25452; }","duration":"139.235553ms","start":"2026-07-15T22:35:50.002136Z","end":"2026-07-15T22:35:50.141372Z","steps":["trace[521503994] 'count revisions from in-memory index tree' (duration: 139.015899ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:50.141451Z","caller":"traceutil/trace.go:171","msg":"trace[482479023] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25452; }","duration":"219.015118ms","start":"2026-07-15T22:35:49.922369Z","end":"2026-07-15T22:35:50.141385Z","steps":["trace[482479023] 'count revisions from in-memory index tree' (duration: 218.362805ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:50.168969Z","caller":"traceutil/trace.go:171","msg":"trace[850575587] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"149.490218ms","start":"2026-07-15T22:35:50.019444Z","end":"2026-07-15T22:35:50.168934Z","steps":["trace[850575587] 'process raft request' (duration: 149.277064ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:54.397777Z","caller":"traceutil/trace.go:171","msg":"trace[1274258104] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"272.405399ms","start":"2026-07-15T22:35:54.12534Z","end":"2026-07-15T22:35:54.397745Z","steps":["trace[1274258104] 'process raft request' (duration: 272.189275ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:54.546756Z","caller":"traceutil/trace.go:171","msg":"trace[1202791847] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"363.729776ms","start":"2026-07-15T22:35:54.182975Z","end":"2026-07-15T22:35:54.546705Z","steps":["trace[1202791847] 'process raft request' (duration: 305.850639ms)","trace[1202791847] 'compare' (duration: 57.585632ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:35:54.547023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:54.182954Z","time spent":"363.916039ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:35:54.546751Z","caller":"traceutil/trace.go:171","msg":"trace[195421489] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"246.202118ms","start":"2026-07-15T22:35:54.300505Z","end":"2026-07-15T22:35:54.546707Z","steps":["trace[195421489] 'process raft request' (duration: 246.104556ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:54.634517Z","caller":"traceutil/trace.go:171","msg":"trace[70711069] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"169.974581ms","start":"2026-07-15T22:35:54.464503Z","end":"2026-07-15T22:35:54.634477Z","steps":["trace[70711069] 'process raft request' (duration: 169.439171ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:54.799322Z","caller":"traceutil/trace.go:171","msg":"trace[916574146] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26314; }","duration":"107.187159ms","start":"2026-07-15T22:35:54.69209Z","end":"2026-07-15T22:35:54.799278Z","steps":["trace[916574146] 'read index received' (duration: 44.855587ms)","trace[916574146] 'applied index is now lower than readState.Index' (duration: 62.329572ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:54.799478Z","caller":"traceutil/trace.go:171","msg":"trace[1879212054] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"144.464183ms","start":"2026-07-15T22:35:54.654973Z","end":"2026-07-15T22:35:54.799437Z","steps":["trace[1879212054] 'process raft request' (duration: 82.05527ms)","trace[1879212054] 'compare' (duration: 61.869863ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:35:54.799662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.550927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T22:35:54.799713Z","caller":"traceutil/trace.go:171","msg":"trace[1091207098] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25492; }","duration":"107.673619ms","start":"2026-07-15T22:35:54.692019Z","end":"2026-07-15T22:35:54.799693Z","steps":["trace[1091207098] 'agreement among raft nodes before linearized reading' (duration: 107.429694ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:55.098354Z","caller":"traceutil/trace.go:171","msg":"trace[28627606] linearizableReadLoop","detail":"{readStateIndex:26316; appliedIndex:26315; }","duration":"298.906476ms","start":"2026-07-15T22:35:54.799414Z","end":"2026-07-15T22:35:55.098321Z","steps":["trace[28627606] 'read index received' (duration: 298.667461ms)","trace[28627606] 'applied index is now lower than readState.Index' (duration: 237.775µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:55.098459Z","caller":"traceutil/trace.go:171","msg":"trace[610387463] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"334.424686ms","start":"2026-07-15T22:35:54.763977Z","end":"2026-07-15T22:35:55.098402Z","steps":["trace[610387463] 'process raft request' (duration: 334.1433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:55.098521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.407833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T22:35:55.098564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.318839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T22:35:55.0986Z","caller":"traceutil/trace.go:171","msg":"trace[2075597904] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25493; }","duration":"313.512506ms","start":"2026-07-15T22:35:54.785069Z","end":"2026-07-15T22:35:55.098581Z","steps":["trace[2075597904] 'agreement among raft nodes before linearized reading' (duration: 313.326732ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:55.098611Z","caller":"traceutil/trace.go:171","msg":"trace[1856926340] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:25493; }","duration":"363.464481ms","start":"2026-07-15T22:35:54.735132Z","end":"2026-07-15T22:35:55.098596Z","steps":["trace[1856926340] 'agreement among raft nodes before linearized reading' (duration: 363.332589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:55.09864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:54.785006Z","time spent":"313.622598ms","remote":"127.0.0.1:59992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":27,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:35:55.09866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:54.735104Z","time spent":"363.543052ms","remote":"127.0.0.1:38672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:35:55.098667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:54.763954Z","time spent":"334.595649ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:35:55.107469Z","caller":"traceutil/trace.go:171","msg":"trace[2615128] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"303.467652ms","start":"2026-07-15T22:35:54.803985Z","end":"2026-07-15T22:35:55.107453Z","steps":["trace[2615128] 'process raft request' (duration: 303.3291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:55.107602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:54.803964Z","time spent":"303.562115ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:35:55.107592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.356556ms","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-07-15T22:35:55.107646Z","caller":"traceutil/trace.go:171","msg":"trace[2107115484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25494; }","duration":"229.469548ms","start":"2026-07-15T22:35:54.878163Z","end":"2026-07-15T22:35:55.107633Z","steps":["trace[2107115484] 'agreement among raft nodes before linearized reading' (duration: 229.272334ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:55.318125Z","caller":"traceutil/trace.go:171","msg":"trace[259446922] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"204.999151ms","start":"2026-07-15T22:35:55.113098Z","end":"2026-07-15T22:35:55.318098Z","steps":["trace[259446922] 'process raft request' (duration: 144.462862ms)","trace[259446922] 'compare' (duration: 60.111811ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:55.623636Z","caller":"traceutil/trace.go:171","msg":"trace[79889154] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"300.654169ms","start":"2026-07-15T22:35:55.322927Z","end":"2026-07-15T22:35:55.623581Z","steps":["trace[79889154] 'process raft request' (duration: 299.358925ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:55.623674Z","caller":"traceutil/trace.go:171","msg":"trace[80876731] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"300.518397ms","start":"2026-07-15T22:35:55.323129Z","end":"2026-07-15T22:35:55.623648Z","steps":["trace[80876731] 'process raft request' (duration: 300.384144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:55.623834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:55.323106Z","time spent":"300.637529ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:35:55.623853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:55.322894Z","time spent":"300.839543ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:35:55.624105Z","caller":"traceutil/trace.go:171","msg":"trace[2126190] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"300.819593ms","start":"2026-07-15T22:35:55.323262Z","end":"2026-07-15T22:35:55.624081Z","steps":["trace[2126190] 'process raft request' (duration: 300.337954ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:55.624133Z","caller":"traceutil/trace.go:171","msg":"trace[329949966] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"300.491416ms","start":"2026-07-15T22:35:55.323611Z","end":"2026-07-15T22:35:55.624102Z","steps":["trace[329949966] 'process raft request' (duration: 300.313283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:55.624204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:55.323242Z","time spent":"300.908434ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:35:55.624305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:55.323599Z","time spent":"300.610038ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:35:55.700371Z","caller":"traceutil/trace.go:171","msg":"trace[46640976] linearizableReadLoop","detail":"{readStateIndex:26324; appliedIndex:26319; }","duration":"225.595674ms","start":"2026-07-15T22:35:55.474755Z","end":"2026-07-15T22:35:55.700351Z","steps":["trace[46640976] 'read index received' (duration: 147.546752ms)","trace[46640976] 'applied index is now lower than readState.Index' (duration: 78.047892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:35:55.700513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.750058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:35:55.700469Z","caller":"traceutil/trace.go:171","msg":"trace[1691778418] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"274.712884ms","start":"2026-07-15T22:35:55.425708Z","end":"2026-07-15T22:35:55.70042Z","steps":["trace[1691778418] 'process raft request' (duration: 274.53803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:55.700543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.21469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:35:55.700565Z","caller":"traceutil/trace.go:171","msg":"trace[767427069] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25501; }","duration":"225.85593ms","start":"2026-07-15T22:35:55.474695Z","end":"2026-07-15T22:35:55.70055Z","steps":["trace[767427069] 'agreement among raft nodes before linearized reading' (duration: 225.768668ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:55.700607Z","caller":"traceutil/trace.go:171","msg":"trace[1995452154] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:25501; }","duration":"130.285002ms","start":"2026-07-15T22:35:55.570304Z","end":"2026-07-15T22:35:55.700589Z","steps":["trace[1995452154] 'agreement among raft nodes before linearized reading' (duration: 130.17979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:56.197422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.404351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:35:56.197543Z","caller":"traceutil/trace.go:171","msg":"trace[1605359951] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25502; }","duration":"121.602955ms","start":"2026-07-15T22:35:56.075918Z","end":"2026-07-15T22:35:56.197521Z","steps":["trace[1605359951] 'range keys from in-memory index tree' (duration: 121.289679ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:56.682863Z","caller":"traceutil/trace.go:171","msg":"trace[723126988] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"129.979936ms","start":"2026-07-15T22:35:56.552848Z","end":"2026-07-15T22:35:56.682828Z","steps":["trace[723126988] 'process raft request' (duration: 129.759581ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:56.784347Z","caller":"traceutil/trace.go:171","msg":"trace[1855379408] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"143.673177ms","start":"2026-07-15T22:35:56.640647Z","end":"2026-07-15T22:35:56.78432Z","steps":["trace[1855379408] 'process raft request' (duration: 143.606486ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:56.784374Z","caller":"traceutil/trace.go:171","msg":"trace[1312169882] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"230.319944ms","start":"2026-07-15T22:35:56.55403Z","end":"2026-07-15T22:35:56.78435Z","steps":["trace[1312169882] 'process raft request' (duration: 230.033348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:56.983512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.888813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:35:56.983653Z","caller":"traceutil/trace.go:171","msg":"trace[416990913] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"179.114346ms","start":"2026-07-15T22:35:56.804516Z","end":"2026-07-15T22:35:56.98363Z","steps":["trace[416990913] 'process raft request' (duration: 37.003138ms)","trace[416990913] 'compare' (duration: 141.677928ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:57.293706Z","caller":"traceutil/trace.go:171","msg":"trace[589230986] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"277.472536ms","start":"2026-07-15T22:35:57.016201Z","end":"2026-07-15T22:35:57.293674Z","steps":["trace[589230986] 'process raft request' (duration: 254.425635ms)","trace[589230986] 'compare' (duration: 22.919729ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:57.305625Z","caller":"traceutil/trace.go:171","msg":"trace[769910198] linearizableReadLoop","detail":"{readStateIndex:26331; appliedIndex:26329; }","duration":"193.225145ms","start":"2026-07-15T22:35:57.112376Z","end":"2026-07-15T22:35:57.305601Z","steps":["trace[769910198] 'read index received' (duration: 158.210025ms)","trace[769910198] 'applied index is now lower than readState.Index' (duration: 35.01398ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:57.305756Z","caller":"traceutil/trace.go:171","msg":"trace[115572307] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"200.543976ms","start":"2026-07-15T22:35:57.1052Z","end":"2026-07-15T22:35:57.305744Z","steps":["trace[115572307] 'process raft request' (duration: 200.256949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:57.306003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.616603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T22:35:57.306033Z","caller":"traceutil/trace.go:171","msg":"trace[714959586] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25508; }","duration":"193.664174ms","start":"2026-07-15T22:35:57.11236Z","end":"2026-07-15T22:35:57.306024Z","steps":["trace[714959586] 'agreement among raft nodes before linearized reading' (duration: 193.535981ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:57.480714Z","caller":"traceutil/trace.go:171","msg":"trace[1927206610] linearizableReadLoop","detail":"{readStateIndex:26333; appliedIndex:26331; }","duration":"159.301997ms","start":"2026-07-15T22:35:57.321389Z","end":"2026-07-15T22:35:57.480691Z","steps":["trace[1927206610] 'read index received' (duration: 10.641094ms)","trace[1927206610] 'applied index is now lower than readState.Index' (duration: 148.660053ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:57.481258Z","caller":"traceutil/trace.go:171","msg":"trace[1055197666] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"170.9739ms","start":"2026-07-15T22:35:57.310275Z","end":"2026-07-15T22:35:57.481249Z","steps":["trace[1055197666] 'process raft request' (duration: 170.272337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:57.481482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.072081ms","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-07-15T22:35:57.481506Z","caller":"traceutil/trace.go:171","msg":"trace[1196457559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25510; }","duration":"160.139352ms","start":"2026-07-15T22:35:57.32136Z","end":"2026-07-15T22:35:57.4815Z","steps":["trace[1196457559] 'agreement among raft nodes before linearized reading' (duration: 160.02466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:57.481875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.473351ms","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-07-15T22:35:57.482031Z","caller":"traceutil/trace.go:171","msg":"trace[609049354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25510; }","duration":"146.696925ms","start":"2026-07-15T22:35:57.335303Z","end":"2026-07-15T22:35:57.482Z","steps":["trace[609049354] 'agreement among raft nodes before linearized reading' (duration: 146.313358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:57.76019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.445569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:35:57.760442Z","caller":"traceutil/trace.go:171","msg":"trace[542778498] linearizableReadLoop","detail":"{readStateIndex:26335; appliedIndex:26333; }","duration":"132.33005ms","start":"2026-07-15T22:35:57.628089Z","end":"2026-07-15T22:35:57.760419Z","steps":["trace[542778498] 'read index received' (duration: 119.852461ms)","trace[542778498] 'applied index is now lower than readState.Index' (duration: 12.475939ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:57.76055Z","caller":"traceutil/trace.go:171","msg":"trace[1990250435] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"274.262375ms","start":"2026-07-15T22:35:57.486276Z","end":"2026-07-15T22:35:57.760539Z","steps":["trace[1990250435] 'process raft request' (duration: 274.042631ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:57.760663Z","caller":"traceutil/trace.go:171","msg":"trace[1226873715] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"274.876797ms","start":"2026-07-15T22:35:57.485736Z","end":"2026-07-15T22:35:57.760613Z","steps":["trace[1226873715] 'process raft request' (duration: 125.897988ms)","trace[1226873715] 'compare' (duration: 147.795047ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:35:57.760868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.461293ms","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-07-15T22:35:57.761506Z","caller":"traceutil/trace.go:171","msg":"trace[1065974455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25512; }","duration":"133.000623ms","start":"2026-07-15T22:35:57.628374Z","end":"2026-07-15T22:35:57.761375Z","steps":["trace[1065974455] 'agreement among raft nodes before linearized reading' (duration: 132.330011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:57.760953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.878811ms","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-07-15T22:35:57.761576Z","caller":"traceutil/trace.go:171","msg":"trace[1907198413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25512; }","duration":"133.544914ms","start":"2026-07-15T22:35:57.628019Z","end":"2026-07-15T22:35:57.761564Z","steps":["trace[1907198413] 'agreement among raft nodes before linearized reading' (duration: 132.878511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:57.761044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.477264ms","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-07-15T22:35:57.761686Z","caller":"traceutil/trace.go:171","msg":"trace[995365167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25512; }","duration":"133.178197ms","start":"2026-07-15T22:35:57.628497Z","end":"2026-07-15T22:35:57.761676Z","steps":["trace[995365167] 'agreement among raft nodes before linearized reading' (duration: 132.479054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:57.761168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.214139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-15T22:35:57.761803Z","caller":"traceutil/trace.go:171","msg":"trace[1594087541] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25512; }","duration":"132.892991ms","start":"2026-07-15T22:35:57.628894Z","end":"2026-07-15T22:35:57.761787Z","steps":["trace[1594087541] 'agreement among raft nodes before linearized reading' (duration: 132.057115ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:57.983737Z","caller":"traceutil/trace.go:171","msg":"trace[711493410] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"216.092703ms","start":"2026-07-15T22:35:57.767605Z","end":"2026-07-15T22:35:57.983698Z","steps":["trace[711493410] 'process raft request' (duration: 201.380191ms)","trace[711493410] 'compare' (duration: 14.381935ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:57.983801Z","caller":"traceutil/trace.go:171","msg":"trace[1687857187] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"216.085213ms","start":"2026-07-15T22:35:57.767695Z","end":"2026-07-15T22:35:57.98378Z","steps":["trace[1687857187] 'process raft request' (duration: 215.902739ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:57.983851Z","caller":"traceutil/trace.go:171","msg":"trace[1017882022] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"216.023992ms","start":"2026-07-15T22:35:57.767791Z","end":"2026-07-15T22:35:57.983815Z","steps":["trace[1017882022] 'process raft request' (duration: 215.91064ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:58.869076Z","caller":"traceutil/trace.go:171","msg":"trace[1697266175] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"180.584764ms","start":"2026-07-15T22:35:58.688461Z","end":"2026-07-15T22:35:58.869046Z","steps":["trace[1697266175] 'process raft request' (duration: 180.401201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:59.050944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.41493ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-15T22:35:59.051726Z","caller":"traceutil/trace.go:171","msg":"trace[1028301638] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"259.818198ms","start":"2026-07-15T22:35:58.791862Z","end":"2026-07-15T22:35:59.051681Z","steps":["trace[1028301638] 'process raft request' (duration: 259.208007ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:59.09484Z","caller":"traceutil/trace.go:171","msg":"trace[128020573] linearizableReadLoop","detail":"{readStateIndex:26344; appliedIndex:26340; }","duration":"283.97332ms","start":"2026-07-15T22:35:58.810789Z","end":"2026-07-15T22:35:59.094762Z","steps":["trace[128020573] 'read index received' (duration: 58.069061ms)","trace[128020573] 'applied index is now lower than readState.Index' (duration: 225.903089ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:59.094934Z","caller":"traceutil/trace.go:171","msg":"trace[40627539] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"300.71015ms","start":"2026-07-15T22:35:58.794198Z","end":"2026-07-15T22:35:59.094909Z","steps":["trace[40627539] 'process raft request' (duration: 257.459323ms)","trace[40627539] 'compare' (duration: 42.938811ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:35:59.09504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.229315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T22:35:59.095085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:58.794182Z","time spent":"300.797472ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:35:59.095151Z","caller":"traceutil/trace.go:171","msg":"trace[1260484203] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25520; }","duration":"284.348318ms","start":"2026-07-15T22:35:58.81078Z","end":"2026-07-15T22:35:59.095128Z","steps":["trace[1260484203] 'agreement among raft nodes before linearized reading' (duration: 284.187915ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:35:59.238049Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066ea.wal"} {"level":"info","ts":"2026-07-15T22:35:59.23909Z","caller":"traceutil/trace.go:171","msg":"trace[61652678] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"302.970714ms","start":"2026-07-15T22:35:58.936084Z","end":"2026-07-15T22:35:59.239055Z","steps":["trace[61652678] 'process raft request' (duration: 302.486705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:59.239281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:35:58.936047Z","time spent":"303.118016ms","remote":"127.0.0.1:49690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418587,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:35:59.240055Z","caller":"traceutil/trace.go:171","msg":"trace[1924753556] linearizableReadLoop","detail":"{readStateIndex:26346; appliedIndex:26345; }","duration":"145.102135ms","start":"2026-07-15T22:35:59.094925Z","end":"2026-07-15T22:35:59.240027Z","steps":["trace[1924753556] 'read index received' (duration: 144.28667ms)","trace[1924753556] 'applied index is now lower than readState.Index' (duration: 813.665µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:35:59.24017Z","caller":"traceutil/trace.go:171","msg":"trace[1530535701] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"250.293916ms","start":"2026-07-15T22:35:58.989846Z","end":"2026-07-15T22:35:59.240139Z","steps":["trace[1530535701] 'process raft request' (duration: 250.017511ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:35:59.240387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.636669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T22:35:59.240438Z","caller":"traceutil/trace.go:171","msg":"trace[1396127117] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:25522; }","duration":"232.71811ms","start":"2026-07-15T22:35:59.007704Z","end":"2026-07-15T22:35:59.240422Z","steps":["trace[1396127117] 'agreement among raft nodes before linearized reading' (duration: 232.431775ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:10.052336Z","caller":"traceutil/trace.go:171","msg":"trace[104513150] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"228.997639ms","start":"2026-07-15T22:36:09.823321Z","end":"2026-07-15T22:36:10.052319Z","steps":["trace[104513150] 'process raft request' (duration: 228.858006ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:10.083328Z","caller":"traceutil/trace.go:171","msg":"trace[203255950] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"255.983305ms","start":"2026-07-15T22:36:09.827322Z","end":"2026-07-15T22:36:10.083305Z","steps":["trace[203255950] 'process raft request' (duration: 255.822442ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:10.08351Z","caller":"traceutil/trace.go:171","msg":"trace[1999260311] linearizableReadLoop","detail":"{readStateIndex:26429; appliedIndex:26427; }","duration":"249.621663ms","start":"2026-07-15T22:36:09.833881Z","end":"2026-07-15T22:36:10.083503Z","steps":["trace[1999260311] 'read index received' (duration: 218.384866ms)","trace[1999260311] 'applied index is now lower than readState.Index' (duration: 31.235837ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:10.083741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.843037ms","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-07-15T22:36:10.083792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.158972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:10.083818Z","caller":"traceutil/trace.go:171","msg":"trace[790775839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25604; }","duration":"194.260755ms","start":"2026-07-15T22:36:09.889551Z","end":"2026-07-15T22:36:10.083812Z","steps":["trace[790775839] 'agreement among raft nodes before linearized reading' (duration: 194.198154ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:10.083829Z","caller":"traceutil/trace.go:171","msg":"trace[1464988137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25604; }","duration":"249.99308ms","start":"2026-07-15T22:36:09.833817Z","end":"2026-07-15T22:36:10.08381Z","steps":["trace[1464988137] 'agreement among raft nodes before linearized reading' (duration: 249.798356ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:10.640567Z","caller":"traceutil/trace.go:171","msg":"trace[1615643820] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"551.472107ms","start":"2026-07-15T22:36:10.089062Z","end":"2026-07-15T22:36:10.640534Z","steps":["trace[1615643820] 'process raft request' (duration: 530.856482ms)","trace[1615643820] 'compare' (duration: 20.482922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:10.640742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:10.089049Z","time spent":"551.598569ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:10.641505Z","caller":"traceutil/trace.go:171","msg":"trace[254325470] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"552.104188ms","start":"2026-07-15T22:36:10.089363Z","end":"2026-07-15T22:36:10.641467Z","steps":["trace[254325470] 'process raft request' (duration: 552.055447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:10.641702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:10.089346Z","time spent":"552.251061ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:10.642038Z","caller":"traceutil/trace.go:171","msg":"trace[901133338] transaction","detail":"{read_only:false; response_revision:25606; number_of_response:1; }","duration":"552.759791ms","start":"2026-07-15T22:36:10.089266Z","end":"2026-07-15T22:36:10.642025Z","steps":["trace[901133338] 'process raft request' (duration: 551.997256ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:10.642496Z","caller":"traceutil/trace.go:171","msg":"trace[1036418007] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"553.114407ms","start":"2026-07-15T22:36:10.089344Z","end":"2026-07-15T22:36:10.642458Z","steps":["trace[1036418007] 'process raft request' (duration: 552.039047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:10.642605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:10.089246Z","time spent":"553.29693ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:10.642645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:10.089336Z","time spent":"553.226099ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:10.983837Z","caller":"traceutil/trace.go:171","msg":"trace[2104070877] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"269.662837ms","start":"2026-07-15T22:36:10.714145Z","end":"2026-07-15T22:36:10.983808Z","steps":["trace[2104070877] 'process raft request' (duration: 239.737235ms)","trace[2104070877] 'compare' (duration: 26.844173ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:10.983999Z","caller":"traceutil/trace.go:171","msg":"trace[1407849445] linearizableReadLoop","detail":"{readStateIndex:26435; appliedIndex:26434; }","duration":"154.24874ms","start":"2026-07-15T22:36:10.82974Z","end":"2026-07-15T22:36:10.983988Z","steps":["trace[1407849445] 'read index received' (duration: 124.157556ms)","trace[1407849445] 'applied index is now lower than readState.Index' (duration: 30.090324ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:10.984083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.328741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:10.984124Z","caller":"traceutil/trace.go:171","msg":"trace[326052480] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25610; }","duration":"154.430414ms","start":"2026-07-15T22:36:10.829682Z","end":"2026-07-15T22:36:10.984112Z","steps":["trace[326052480] 'agreement among raft nodes before linearized reading' (duration: 154.348122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:11.7193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882308397974160,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T22:36:11.747609Z","caller":"traceutil/trace.go:171","msg":"trace[1315785981] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"655.438024ms","start":"2026-07-15T22:36:11.09213Z","end":"2026-07-15T22:36:11.747568Z","steps":["trace[1315785981] 'process raft request' (duration: 655.197139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:11.747864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.092098Z","time spent":"655.634058ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:11.781998Z","caller":"traceutil/trace.go:171","msg":"trace[213017967] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"499.265587ms","start":"2026-07-15T22:36:11.282698Z","end":"2026-07-15T22:36:11.781964Z","steps":["trace[213017967] 'process raft request' (duration: 499.170795ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:11.78202Z","caller":"traceutil/trace.go:171","msg":"trace[1335148113] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"648.289287ms","start":"2026-07-15T22:36:11.133693Z","end":"2026-07-15T22:36:11.781982Z","steps":["trace[1335148113] 'process raft request' (duration: 647.987761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:11.782291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.989705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:11.782299Z","caller":"traceutil/trace.go:171","msg":"trace[1712719430] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"415.046167ms","start":"2026-07-15T22:36:11.36723Z","end":"2026-07-15T22:36:11.782276Z","steps":["trace[1712719430] 'process raft request' (duration: 414.69209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:11.782322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.133661Z","time spent":"648.573822ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:11.782371Z","caller":"traceutil/trace.go:171","msg":"trace[861740043] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25614; }","duration":"564.108228ms","start":"2026-07-15T22:36:11.218246Z","end":"2026-07-15T22:36:11.782354Z","steps":["trace[861740043] 'agreement among raft nodes before linearized reading' (duration: 563.967435ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:11.782178Z","caller":"traceutil/trace.go:171","msg":"trace[1233207405] linearizableReadLoop","detail":"{readStateIndex:26440; appliedIndex:26436; }","duration":"563.862563ms","start":"2026-07-15T22:36:11.218295Z","end":"2026-07-15T22:36:11.782158Z","steps":["trace[1233207405] 'read index received' (duration: 528.978216ms)","trace[1233207405] 'applied index is now lower than readState.Index' (duration: 34.882277ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:11.782433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.21821Z","time spent":"564.209659ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-15T22:36:11.782453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.367208Z","time spent":"415.162759ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:11.782302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.282663Z","time spent":"499.490832ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:11.782746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.930807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-07-15T22:36:11.782741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.769533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-15T22:36:11.782794Z","caller":"traceutil/trace.go:171","msg":"trace[2007088869] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25614; }","duration":"127.031248ms","start":"2026-07-15T22:36:11.655746Z","end":"2026-07-15T22:36:11.782777Z","steps":["trace[2007088869] 'agreement among raft nodes before linearized reading' (duration: 126.894436ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:11.782804Z","caller":"traceutil/trace.go:171","msg":"trace[1534116686] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25614; }","duration":"309.886526ms","start":"2026-07-15T22:36:11.4729Z","end":"2026-07-15T22:36:11.782787Z","steps":["trace[1534116686] 'agreement among raft nodes before linearized reading' (duration: 309.643451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:11.783356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.472876Z","time spent":"310.049048ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-15T22:36:12.049115Z","caller":"traceutil/trace.go:171","msg":"trace[1619782645] linearizableReadLoop","detail":"{readStateIndex:26441; appliedIndex:26440; }","duration":"260.93006ms","start":"2026-07-15T22:36:11.78816Z","end":"2026-07-15T22:36:12.04909Z","steps":["trace[1619782645] 'read index received' (duration: 253.467878ms)","trace[1619782645] 'applied index is now lower than readState.Index' (duration: 7.461112ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:12.049231Z","caller":"traceutil/trace.go:171","msg":"trace[445288382] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"261.626394ms","start":"2026-07-15T22:36:11.787571Z","end":"2026-07-15T22:36:12.049197Z","steps":["trace[445288382] 'process raft request' (duration: 254.034769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.049347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.167886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-15T22:36:12.049372Z","caller":"traceutil/trace.go:171","msg":"trace[1237148514] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25615; }","duration":"261.283648ms","start":"2026-07-15T22:36:11.788081Z","end":"2026-07-15T22:36:12.049364Z","steps":["trace[1237148514] 'agreement among raft nodes before linearized reading' (duration: 261.122414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.68918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.642045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:12.689448Z","caller":"traceutil/trace.go:171","msg":"trace[785642719] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"745.935055ms","start":"2026-07-15T22:36:11.943474Z","end":"2026-07-15T22:36:12.689409Z","steps":["trace[785642719] 'process raft request' (duration: 428.918122ms)","trace[785642719] 'compare' (duration: 316.259558ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:12.689563Z","caller":"traceutil/trace.go:171","msg":"trace[2001200255] linearizableReadLoop","detail":"{readStateIndex:26442; appliedIndex:26441; }","duration":"640.377245ms","start":"2026-07-15T22:36:12.049178Z","end":"2026-07-15T22:36:12.689555Z","steps":["trace[2001200255] 'read index received' (duration: 323.230111ms)","trace[2001200255] 'applied index is now lower than readState.Index' (duration: 317.146024ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:12.689665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.943449Z","time spent":"746.123228ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:12.689521Z","caller":"traceutil/trace.go:171","msg":"trace[2070007881] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"331.832735ms","start":"2026-07-15T22:36:12.357668Z","end":"2026-07-15T22:36:12.689501Z","steps":["trace[2070007881] 'process raft request' (duration: 331.758014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.68985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.357629Z","time spent":"332.185122ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:12.690013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.373377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18144"} {"level":"info","ts":"2026-07-15T22:36:12.69004Z","caller":"traceutil/trace.go:171","msg":"trace[2097575841] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25618; }","duration":"639.440637ms","start":"2026-07-15T22:36:12.050593Z","end":"2026-07-15T22:36:12.690033Z","steps":["trace[2097575841] 'agreement among raft nodes before linearized reading' (duration: 639.331205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.69006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.050568Z","time spent":"639.487929ms","remote":"127.0.0.1:44866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18165,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-15T22:36:12.690147Z","caller":"traceutil/trace.go:171","msg":"trace[1453728142] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"599.180079ms","start":"2026-07-15T22:36:12.090926Z","end":"2026-07-15T22:36:12.690106Z","steps":["trace[1453728142] 'process raft request' (duration: 598.394494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.690279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.844323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:12.690309Z","caller":"traceutil/trace.go:171","msg":"trace[45923152] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:25618; }","duration":"311.915964ms","start":"2026-07-15T22:36:12.378386Z","end":"2026-07-15T22:36:12.690302Z","steps":["trace[45923152] 'agreement among raft nodes before linearized reading' (duration: 311.862213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.69033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.378369Z","time spent":"311.955085ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:36:12.690328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.090907Z","time spent":"599.323961ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:12.690454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.491763ms","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-07-15T22:36:12.690479Z","caller":"traceutil/trace.go:171","msg":"trace[58238919] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25618; }","duration":"406.553265ms","start":"2026-07-15T22:36:12.283918Z","end":"2026-07-15T22:36:12.690471Z","steps":["trace[58238919] 'agreement among raft nodes before linearized reading' (duration: 406.511354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.690498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.283891Z","time spent":"406.602225ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:36:12.690503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.497055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:12.690542Z","caller":"traceutil/trace.go:171","msg":"trace[2120609245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25618; }","duration":"803.582667ms","start":"2026-07-15T22:36:11.886949Z","end":"2026-07-15T22:36:12.690531Z","steps":["trace[2120609245] 'agreement among raft nodes before linearized reading' (duration: 803.508995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.690546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.135007ms","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-07-15T22:36:12.690587Z","caller":"traceutil/trace.go:171","msg":"trace[1843036633] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25618; }","duration":"634.208898ms","start":"2026-07-15T22:36:12.056368Z","end":"2026-07-15T22:36:12.690576Z","steps":["trace[1843036633] 'agreement among raft nodes before linearized reading' (duration: 634.112016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.690581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:11.886926Z","time spent":"803.649428ms","remote":"127.0.0.1:44682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T22:36:12.69062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.056353Z","time spent":"634.257019ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T22:36:12.690878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.245177ms","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-07-15T22:36:12.690921Z","caller":"traceutil/trace.go:171","msg":"trace[808966781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25618; }","duration":"602.321739ms","start":"2026-07-15T22:36:12.088587Z","end":"2026-07-15T22:36:12.690909Z","steps":["trace[808966781] 'agreement among raft nodes before linearized reading' (duration: 602.227077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.690915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.757642ms","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-07-15T22:36:12.690954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.088571Z","time spent":"602.369939ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T22:36:12.690987Z","caller":"traceutil/trace.go:171","msg":"trace[1134091443] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25618; }","duration":"631.877794ms","start":"2026-07-15T22:36:12.059092Z","end":"2026-07-15T22:36:12.690969Z","steps":["trace[1134091443] 'agreement among raft nodes before linearized reading' (duration: 631.751972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:12.691034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:12.059077Z","time spent":"631.945855ms","remote":"127.0.0.1:44836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":16,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:36:14.212833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.218645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T22:36:14.213024Z","caller":"traceutil/trace.go:171","msg":"trace[1493973922] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25687; }","duration":"159.46374ms","start":"2026-07-15T22:36:14.05353Z","end":"2026-07-15T22:36:14.212993Z","steps":["trace[1493973922] 'range keys from in-memory index tree' (duration: 158.991652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:14.213381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.111353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34fa8745-c982-4f68-8c0c-3c061c29ba6f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-15T22:36:14.213471Z","caller":"traceutil/trace.go:171","msg":"trace[2059820760] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34fa8745-c982-4f68-8c0c-3c061c29ba6f; range_end:; response_count:1; response_revision:25687; }","duration":"148.191034ms","start":"2026-07-15T22:36:14.065237Z","end":"2026-07-15T22:36:14.213428Z","steps":["trace[2059820760] 'range keys from in-memory index tree' (duration: 148.015441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:14.213777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.494629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-34fa8745-c982-4f68-8c0c-3c061c29ba6f\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-15T22:36:14.213896Z","caller":"traceutil/trace.go:171","msg":"trace[1032258383] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-34fa8745-c982-4f68-8c0c-3c061c29ba6f; range_end:; response_count:1; response_revision:25687; }","duration":"104.654152ms","start":"2026-07-15T22:36:14.109219Z","end":"2026-07-15T22:36:14.213873Z","steps":["trace[1032258383] 'range keys from in-memory index tree' (duration: 104.351766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:14.214191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.281434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-07-15T22:36:14.214294Z","caller":"traceutil/trace.go:171","msg":"trace[1615419414] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25687; }","duration":"127.408216ms","start":"2026-07-15T22:36:14.086863Z","end":"2026-07-15T22:36:14.214271Z","steps":["trace[1615419414] 'range keys from in-memory index tree' (duration: 127.1015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:16.562629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.36505ms","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-07-15T22:36:16.562734Z","caller":"traceutil/trace.go:171","msg":"trace[1326234940] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25705; }","duration":"214.512523ms","start":"2026-07-15T22:36:16.348197Z","end":"2026-07-15T22:36:16.562709Z","steps":["trace[1326234940] 'range keys from in-memory index tree' (duration: 214.313619ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:19.602676Z","caller":"traceutil/trace.go:171","msg":"trace[1888210679] linearizableReadLoop","detail":"{readStateIndex:26577; appliedIndex:26576; }","duration":"353.606902ms","start":"2026-07-15T22:36:19.249042Z","end":"2026-07-15T22:36:19.602649Z","steps":["trace[1888210679] 'read index received' (duration: 353.315276ms)","trace[1888210679] 'applied index is now lower than readState.Index' (duration: 290.726µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:19.602756Z","caller":"traceutil/trace.go:171","msg":"trace[1660866902] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"413.409816ms","start":"2026-07-15T22:36:19.189308Z","end":"2026-07-15T22:36:19.602718Z","steps":["trace[1660866902] 'process raft request' (duration: 413.11503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:19.602922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.857017ms","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":"warn","ts":"2026-07-15T22:36:19.602937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.919485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T22:36:19.602977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:19.189293Z","time spent":"413.542609ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10634,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:19.603041Z","caller":"traceutil/trace.go:171","msg":"trace[2061459989] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25747; }","duration":"152.079838ms","start":"2026-07-15T22:36:19.450939Z","end":"2026-07-15T22:36:19.603018Z","steps":["trace[2061459989] 'agreement among raft nodes before linearized reading' (duration: 151.899405ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:19.602969Z","caller":"traceutil/trace.go:171","msg":"trace[576655433] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25747; }","duration":"353.960518ms","start":"2026-07-15T22:36:19.248997Z","end":"2026-07-15T22:36:19.602957Z","steps":["trace[576655433] 'agreement among raft nodes before linearized reading' (duration: 353.787195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:19.603194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:19.248976Z","time spent":"354.177163ms","remote":"127.0.0.1:44846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-07-15T22:36:19.990398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.964761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:19.990562Z","caller":"traceutil/trace.go:171","msg":"trace[2040300779] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"381.636439ms","start":"2026-07-15T22:36:19.608896Z","end":"2026-07-15T22:36:19.990533Z","steps":["trace[2040300779] 'process raft request' (duration: 41.397853ms)","trace[2040300779] 'compare' (duration: 339.047873ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:19.990656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:19.608864Z","time spent":"381.74861ms","remote":"127.0.0.1:44854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:20.010961Z","caller":"traceutil/trace.go:171","msg":"trace[1701407910] linearizableReadLoop","detail":"{readStateIndex:26581; appliedIndex:26578; }","duration":"353.830316ms","start":"2026-07-15T22:36:19.657108Z","end":"2026-07-15T22:36:20.010938Z","steps":["trace[1701407910] 'read index received' (duration: 353.493479ms)","trace[1701407910] 'applied index is now lower than readState.Index' (duration: 335.727µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:20.011399Z","caller":"traceutil/trace.go:171","msg":"trace[1660504610] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"400.705212ms","start":"2026-07-15T22:36:19.610658Z","end":"2026-07-15T22:36:20.011363Z","steps":["trace[1660504610] 'process raft request' (duration: 400.223563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:20.011596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:19.610636Z","time spent":"400.858446ms","remote":"127.0.0.1:44972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:20.011312Z","caller":"traceutil/trace.go:171","msg":"trace[108743949] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"400.984048ms","start":"2026-07-15T22:36:19.610311Z","end":"2026-07-15T22:36:20.011295Z","steps":["trace[108743949] 'process raft request' (duration: 400.344206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:20.011437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.315346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:20.011813Z","caller":"traceutil/trace.go:171","msg":"trace[1664595414] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25751; }","duration":"354.740394ms","start":"2026-07-15T22:36:19.657055Z","end":"2026-07-15T22:36:20.011795Z","steps":["trace[1664595414] 'agreement among raft nodes before linearized reading' (duration: 354.317756ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:20.011581Z","caller":"traceutil/trace.go:171","msg":"trace[1777975813] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"400.992929ms","start":"2026-07-15T22:36:19.61054Z","end":"2026-07-15T22:36:20.011533Z","steps":["trace[1777975813] 'process raft request' (duration: 400.252745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:20.011871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:19.657034Z","time spent":"354.819966ms","remote":"127.0.0.1:53162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:36:20.012494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:19.610298Z","time spent":"401.449277ms","remote":"127.0.0.1:44854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:20.01272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:19.610524Z","time spent":"401.401616ms","remote":"127.0.0.1:44972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:20.014953Z","caller":"traceutil/trace.go:171","msg":"trace[702250342] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"239.073121ms","start":"2026-07-15T22:36:19.775843Z","end":"2026-07-15T22:36:20.014916Z","steps":["trace[702250342] 'process raft request' (duration: 238.906078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:20.014971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.20911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:20.015034Z","caller":"traceutil/trace.go:171","msg":"trace[775213364] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:25755; }","duration":"210.334752ms","start":"2026-07-15T22:36:19.804687Z","end":"2026-07-15T22:36:20.015022Z","steps":["trace[775213364] 'agreement among raft nodes before linearized reading' (duration: 210.21921ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:20.015195Z","caller":"traceutil/trace.go:171","msg":"trace[1785655622] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"207.63097ms","start":"2026-07-15T22:36:19.807559Z","end":"2026-07-15T22:36:20.01519Z","steps":["trace[1785655622] 'process raft request' (duration: 207.277264ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:20.015281Z","caller":"traceutil/trace.go:171","msg":"trace[555599331] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"206.590601ms","start":"2026-07-15T22:36:19.808687Z","end":"2026-07-15T22:36:20.015277Z","steps":["trace[555599331] 'process raft request' (duration: 206.181113ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:20.015344Z","caller":"traceutil/trace.go:171","msg":"trace[1448197654] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"206.481449ms","start":"2026-07-15T22:36:19.808858Z","end":"2026-07-15T22:36:20.01534Z","steps":["trace[1448197654] 'process raft request' (duration: 206.027111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:20.015473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.600351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:20.015491Z","caller":"traceutil/trace.go:171","msg":"trace[1835068625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25755; }","duration":"126.666392ms","start":"2026-07-15T22:36:19.88882Z","end":"2026-07-15T22:36:20.015486Z","steps":["trace[1835068625] 'agreement among raft nodes before linearized reading' (duration: 126.639441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:51.434122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920882308397975161,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T22:36:51.606409Z","caller":"traceutil/trace.go:171","msg":"trace[80763346] transaction","detail":"{read_only:false; response_revision:26002; number_of_response:1; }","duration":"677.848073ms","start":"2026-07-15T22:36:50.928534Z","end":"2026-07-15T22:36:51.606382Z","steps":["trace[80763346] 'process raft request' (duration: 677.68456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:51.606576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:50.928521Z","time spent":"677.962515ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:51.626269Z","caller":"traceutil/trace.go:171","msg":"trace[939072292] transaction","detail":"{read_only:false; response_revision:26004; number_of_response:1; }","duration":"498.242058ms","start":"2026-07-15T22:36:51.128015Z","end":"2026-07-15T22:36:51.626257Z","steps":["trace[939072292] 'process raft request' (duration: 498.123076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:51.6264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:51.127986Z","time spent":"498.32947ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:51.626195Z","caller":"traceutil/trace.go:171","msg":"trace[685905966] linearizableReadLoop","detail":"{readStateIndex:26841; appliedIndex:26838; }","duration":"695.003881ms","start":"2026-07-15T22:36:50.931172Z","end":"2026-07-15T22:36:51.626176Z","steps":["trace[685905966] 'read index received' (duration: 675.10975ms)","trace[685905966] 'applied index is now lower than readState.Index' (duration: 19.893361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:51.626759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.543811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-07-15T22:36:51.626841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.005512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:51.626854Z","caller":"traceutil/trace.go:171","msg":"trace[365569403] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26004; }","duration":"695.678145ms","start":"2026-07-15T22:36:50.931154Z","end":"2026-07-15T22:36:51.626832Z","steps":["trace[365569403] 'agreement among raft nodes before linearized reading' (duration: 695.317427ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:51.626886Z","caller":"traceutil/trace.go:171","msg":"trace[1812043430] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26004; }","duration":"557.084854ms","start":"2026-07-15T22:36:51.069789Z","end":"2026-07-15T22:36:51.626874Z","steps":["trace[1812043430] 'agreement among raft nodes before linearized reading' (duration: 556.996381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:51.6269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:50.931147Z","time spent":"695.745436ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-15T22:36:51.626922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:51.069773Z","time spent":"557.139955ms","remote":"127.0.0.1:59692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:36:51.627318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.223803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:51.627352Z","caller":"traceutil/trace.go:171","msg":"trace[873502192] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:26004; }","duration":"447.313975ms","start":"2026-07-15T22:36:51.180029Z","end":"2026-07-15T22:36:51.627343Z","steps":["trace[873502192] 'agreement among raft nodes before linearized reading' (duration: 447.254664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:51.627374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:51.180013Z","time spent":"447.355135ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"info","ts":"2026-07-15T22:36:51.627625Z","caller":"traceutil/trace.go:171","msg":"trace[620701770] transaction","detail":"{read_only:false; response_revision:26003; number_of_response:1; }","duration":"697.757014ms","start":"2026-07-15T22:36:50.929848Z","end":"2026-07-15T22:36:51.627605Z","steps":["trace[620701770] 'process raft request' (duration: 696.173383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:51.627759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:50.929837Z","time spent":"697.865115ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:51.787361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.846432ms","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-07-15T22:36:51.78755Z","caller":"traceutil/trace.go:171","msg":"trace[819998870] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26004; }","duration":"132.056456ms","start":"2026-07-15T22:36:51.655469Z","end":"2026-07-15T22:36:51.787525Z","steps":["trace[819998870] 'range keys from in-memory index tree' (duration: 131.82165ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:51.789671Z","caller":"traceutil/trace.go:171","msg":"trace[572504345] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"156.577234ms","start":"2026-07-15T22:36:51.633078Z","end":"2026-07-15T22:36:51.789655Z","steps":["trace[572504345] 'process raft request' (duration: 151.559788ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:52.247974Z","caller":"traceutil/trace.go:171","msg":"trace[765930807] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"108.981164ms","start":"2026-07-15T22:36:52.138964Z","end":"2026-07-15T22:36:52.247945Z","steps":["trace[765930807] 'process raft request' (duration: 108.807241ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:52.586423Z","caller":"traceutil/trace.go:171","msg":"trace[1795831884] linearizableReadLoop","detail":"{readStateIndex:26846; appliedIndex:26842; }","duration":"394.587796ms","start":"2026-07-15T22:36:52.191789Z","end":"2026-07-15T22:36:52.586376Z","steps":["trace[1795831884] 'read index received' (duration: 56.029232ms)","trace[1795831884] 'applied index is now lower than readState.Index' (duration: 338.556714ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:52.586605Z","caller":"traceutil/trace.go:171","msg":"trace[731629035] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"445.955308ms","start":"2026-07-15T22:36:52.140634Z","end":"2026-07-15T22:36:52.586589Z","steps":["trace[731629035] 'process raft request' (duration: 445.682963ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:52.586607Z","caller":"traceutil/trace.go:171","msg":"trace[1626306166] transaction","detail":"{read_only:false; response_revision:26007; number_of_response:1; }","duration":"447.529519ms","start":"2026-07-15T22:36:52.13905Z","end":"2026-07-15T22:36:52.586579Z","steps":["trace[1626306166] 'process raft request' (duration: 446.736664ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:52.586608Z","caller":"traceutil/trace.go:171","msg":"trace[1332036606] transaction","detail":"{read_only:false; response_revision:26008; number_of_response:1; }","duration":"446.672272ms","start":"2026-07-15T22:36:52.139893Z","end":"2026-07-15T22:36:52.586565Z","steps":["trace[1332036606] 'process raft request' (duration: 446.352626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:52.586778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:52.139019Z","time spent":"447.655841ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:52.586795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:52.139884Z","time spent":"446.809834ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:52.586789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:52.1406Z","time spent":"446.052009ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:52.586774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.941498ms","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-07-15T22:36:52.586887Z","caller":"traceutil/trace.go:171","msg":"trace[1375757161] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26009; }","duration":"331.117132ms","start":"2026-07-15T22:36:52.255736Z","end":"2026-07-15T22:36:52.586853Z","steps":["trace[1375757161] 'agreement among raft nodes before linearized reading' (duration: 330.908328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:52.586963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:52.255719Z","time spent":"331.225654ms","remote":"127.0.0.1:40952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:36:52.58689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.145571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T22:36:52.587036Z","caller":"traceutil/trace.go:171","msg":"trace[2147448945] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:26009; }","duration":"275.354315ms","start":"2026-07-15T22:36:52.311661Z","end":"2026-07-15T22:36:52.587015Z","steps":["trace[2147448945] 'agreement among raft nodes before linearized reading' (duration: 275.0866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:52.587092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.306349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-15T22:36:52.587134Z","caller":"traceutil/trace.go:171","msg":"trace[705064350] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26009; }","duration":"395.380181ms","start":"2026-07-15T22:36:52.19174Z","end":"2026-07-15T22:36:52.58712Z","steps":["trace[705064350] 'agreement among raft nodes before linearized reading' (duration: 395.212538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:52.587168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:52.191729Z","time spent":"395.432092ms","remote":"127.0.0.1:44784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":378,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-07-15T22:36:52.916396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.983569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:52.916804Z","caller":"traceutil/trace.go:171","msg":"trace[804000079] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"169.044402ms","start":"2026-07-15T22:36:52.74771Z","end":"2026-07-15T22:36:52.916754Z","steps":["trace[804000079] 'compare' (duration: 160.637592ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.192523Z","caller":"traceutil/trace.go:171","msg":"trace[1007608768] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"259.90932ms","start":"2026-07-15T22:36:52.932538Z","end":"2026-07-15T22:36:53.192447Z","steps":["trace[1007608768] 'process raft request' (duration: 259.743077ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.193206Z","caller":"traceutil/trace.go:171","msg":"trace[1403830131] linearizableReadLoop","detail":"{readStateIndex:26852; appliedIndex:26849; }","duration":"149.125603ms","start":"2026-07-15T22:36:53.044058Z","end":"2026-07-15T22:36:53.193184Z","steps":["trace[1403830131] 'read index received' (duration: 148.240235ms)","trace[1403830131] 'applied index is now lower than readState.Index' (duration: 884.558µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:53.193364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.287615ms","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-07-15T22:36:53.193398Z","caller":"traceutil/trace.go:171","msg":"trace[704739894] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26015; }","duration":"149.401078ms","start":"2026-07-15T22:36:53.043988Z","end":"2026-07-15T22:36:53.193389Z","steps":["trace[704739894] 'agreement among raft nodes before linearized reading' (duration: 149.301826ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.193621Z","caller":"traceutil/trace.go:171","msg":"trace[1445813495] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"260.267108ms","start":"2026-07-15T22:36:52.933344Z","end":"2026-07-15T22:36:53.193612Z","steps":["trace[1445813495] 'process raft request' (duration: 259.687067ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.193701Z","caller":"traceutil/trace.go:171","msg":"trace[10093428] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"256.663528ms","start":"2026-07-15T22:36:52.937029Z","end":"2026-07-15T22:36:53.193692Z","steps":["trace[10093428] 'process raft request' (duration: 256.114568ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.55699Z","caller":"traceutil/trace.go:171","msg":"trace[1388554469] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"106.16951ms","start":"2026-07-15T22:36:53.450781Z","end":"2026-07-15T22:36:53.55695Z","steps":["trace[1388554469] 'process raft request' (duration: 105.912395ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.712158Z","caller":"traceutil/trace.go:171","msg":"trace[1676695838] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"166.749999ms","start":"2026-07-15T22:36:53.545377Z","end":"2026-07-15T22:36:53.712127Z","steps":["trace[1676695838] 'process raft request' (duration: 98.576786ms)","trace[1676695838] 'compare' (duration: 68.035171ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:53.712241Z","caller":"traceutil/trace.go:171","msg":"trace[227754670] linearizableReadLoop","detail":"{readStateIndex:26855; appliedIndex:26854; }","duration":"151.511338ms","start":"2026-07-15T22:36:53.560691Z","end":"2026-07-15T22:36:53.712203Z","steps":["trace[227754670] 'read index received' (duration: 83.401306ms)","trace[227754670] 'applied index is now lower than readState.Index' (duration: 68.105882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:53.71247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.779752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-15T22:36:53.712665Z","caller":"traceutil/trace.go:171","msg":"trace[868633400] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26018; }","duration":"152.036277ms","start":"2026-07-15T22:36:53.56061Z","end":"2026-07-15T22:36:53.712646Z","steps":["trace[868633400] 'agreement among raft nodes before linearized reading' (duration: 151.705461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:53.713006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.138154ms","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-07-15T22:36:53.713058Z","caller":"traceutil/trace.go:171","msg":"trace[1166281039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26018; }","duration":"101.259397ms","start":"2026-07-15T22:36:53.611785Z","end":"2026-07-15T22:36:53.713045Z","steps":["trace[1166281039] 'agreement among raft nodes before linearized reading' (duration: 100.873019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:53.972835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.967961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:53.972988Z","caller":"traceutil/trace.go:171","msg":"trace[1779056989] linearizableReadLoop","detail":"{readStateIndex:26858; appliedIndex:26857; }","duration":"255.326422ms","start":"2026-07-15T22:36:53.717641Z","end":"2026-07-15T22:36:53.972967Z","steps":["trace[1779056989] 'read index received' (duration: 68.736924ms)","trace[1779056989] 'applied index is now lower than readState.Index' (duration: 186.587698ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:53.973156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.508052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:53.973191Z","caller":"traceutil/trace.go:171","msg":"trace[169972158] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26020; }","duration":"191.585613ms","start":"2026-07-15T22:36:53.781595Z","end":"2026-07-15T22:36:53.973181Z","steps":["trace[169972158] 'agreement among raft nodes before linearized reading' (duration: 191.507142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:53.973244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.555617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-15T22:36:53.973272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.979983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-15T22:36:53.973323Z","caller":"traceutil/trace.go:171","msg":"trace[211936461] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26020; }","duration":"255.678729ms","start":"2026-07-15T22:36:53.717625Z","end":"2026-07-15T22:36:53.973304Z","steps":["trace[211936461] 'agreement among raft nodes before linearized reading' (duration: 255.418374ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.973345Z","caller":"traceutil/trace.go:171","msg":"trace[1232860542] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26020; }","duration":"179.079195ms","start":"2026-07-15T22:36:53.794248Z","end":"2026-07-15T22:36:53.973327Z","steps":["trace[1232860542] 'agreement among raft nodes before linearized reading' (duration: 178.870821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:53.973409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.503545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-15T22:36:53.973498Z","caller":"traceutil/trace.go:171","msg":"trace[448843662] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26020; }","duration":"131.652548ms","start":"2026-07-15T22:36:53.841823Z","end":"2026-07-15T22:36:53.973476Z","steps":["trace[448843662] 'agreement among raft nodes before linearized reading' (duration: 131.324072ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:53.974133Z","caller":"traceutil/trace.go:171","msg":"trace[1753859438] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"255.619238ms","start":"2026-07-15T22:36:53.717434Z","end":"2026-07-15T22:36:53.973053Z","steps":["trace[1753859438] 'process raft request' (duration: 97.318701ms)","trace[1753859438] 'compare' (duration: 157.766767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:54.262395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.032326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-15T22:36:54.262483Z","caller":"traceutil/trace.go:171","msg":"trace[1039928174] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26021; }","duration":"187.175468ms","start":"2026-07-15T22:36:54.075285Z","end":"2026-07-15T22:36:54.26246Z","steps":["trace[1039928174] 'range keys from in-memory index tree' (duration: 186.867353ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:54.389365Z","caller":"traceutil/trace.go:171","msg":"trace[1754914603] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"135.342649ms","start":"2026-07-15T22:36:54.253992Z","end":"2026-07-15T22:36:54.389334Z","steps":["trace[1754914603] 'process raft request' (duration: 135.158866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:55.137381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.037815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:55.137623Z","caller":"traceutil/trace.go:171","msg":"trace[1725398753] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"215.003182ms","start":"2026-07-15T22:36:54.922603Z","end":"2026-07-15T22:36:55.137606Z","steps":["trace[1725398753] 'process raft request' (duration: 214.968001ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:55.137686Z","caller":"traceutil/trace.go:171","msg":"trace[2136556115] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"545.443241ms","start":"2026-07-15T22:36:54.592212Z","end":"2026-07-15T22:36:55.137655Z","steps":["trace[2136556115] 'process raft request' (duration: 354.04085ms)","trace[2136556115] 'compare' (duration: 190.729488ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:55.137794Z","caller":"traceutil/trace.go:171","msg":"trace[851664650] linearizableReadLoop","detail":"{readStateIndex:26863; appliedIndex:26860; }","duration":"507.084158ms","start":"2026-07-15T22:36:54.630693Z","end":"2026-07-15T22:36:55.137778Z","steps":["trace[851664650] 'read index received' (duration: 38.874774ms)","trace[851664650] 'applied index is now lower than readState.Index' (duration: 468.207874ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:55.137857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:54.592184Z","time spent":"545.579114ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:55.137838Z","caller":"traceutil/trace.go:171","msg":"trace[1275145644] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"545.547143ms","start":"2026-07-15T22:36:54.592248Z","end":"2026-07-15T22:36:55.137795Z","steps":["trace[1275145644] 'process raft request' (duration: 545.226157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:55.138001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:54.592213Z","time spent":"545.699045ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:55.138108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.409014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T22:36:55.138143Z","caller":"traceutil/trace.go:171","msg":"trace[684335606] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26026; }","duration":"507.447045ms","start":"2026-07-15T22:36:54.630684Z","end":"2026-07-15T22:36:55.138131Z","steps":["trace[684335606] 'agreement among raft nodes before linearized reading' (duration: 507.238051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:55.138186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:54.630627Z","time spent":"507.551596ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-15T22:36:55.138206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.570603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:55.138237Z","caller":"traceutil/trace.go:171","msg":"trace[1302824480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26026; }","duration":"249.617403ms","start":"2026-07-15T22:36:54.888611Z","end":"2026-07-15T22:36:55.138228Z","steps":["trace[1302824480] 'agreement among raft nodes before linearized reading' (duration: 249.567032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:55.138228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.993198ms","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-07-15T22:36:55.138239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.349732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:55.138275Z","caller":"traceutil/trace.go:171","msg":"trace[823064827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26026; }","duration":"267.412744ms","start":"2026-07-15T22:36:54.870851Z","end":"2026-07-15T22:36:55.138263Z","steps":["trace[823064827] 'agreement among raft nodes before linearized reading' (duration: 267.340843ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:55.138271Z","caller":"traceutil/trace.go:171","msg":"trace[1394931032] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:26026; }","duration":"447.07528ms","start":"2026-07-15T22:36:54.691181Z","end":"2026-07-15T22:36:55.138256Z","steps":["trace[1394931032] 'agreement among raft nodes before linearized reading' (duration: 446.994998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:55.138304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:54.691162Z","time spent":"447.134202ms","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-07-15T22:36:55.138343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.523319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:55.138453Z","caller":"traceutil/trace.go:171","msg":"trace[914667897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:26026; }","duration":"263.716743ms","start":"2026-07-15T22:36:54.874706Z","end":"2026-07-15T22:36:55.138423Z","steps":["trace[914667897] 'agreement among raft nodes before linearized reading' (duration: 263.480729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:55.542485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.315613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:55.543067Z","caller":"traceutil/trace.go:171","msg":"trace[74404881] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"342.098712ms","start":"2026-07-15T22:36:55.200888Z","end":"2026-07-15T22:36:55.542987Z","steps":["trace[74404881] 'process raft request' (duration: 41.176308ms)","trace[74404881] 'compare' (duration: 299.206352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:55.543262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:55.200872Z","time spent":"342.281976ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:55.543356Z","caller":"traceutil/trace.go:171","msg":"trace[1406099411] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"341.175825ms","start":"2026-07-15T22:36:55.202154Z","end":"2026-07-15T22:36:55.54333Z","steps":["trace[1406099411] 'process raft request' (duration: 340.47332ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:55.543495Z","caller":"traceutil/trace.go:171","msg":"trace[682189496] linearizableReadLoop","detail":"{readStateIndex:26868; appliedIndex:26865; }","duration":"281.519934ms","start":"2026-07-15T22:36:55.261942Z","end":"2026-07-15T22:36:55.543462Z","steps":["trace[682189496] 'read index received' (duration: 239.195025ms)","trace[682189496] 'applied index is now lower than readState.Index' (duration: 42.322549ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:55.543597Z","caller":"traceutil/trace.go:171","msg":"trace[305620689] transaction","detail":"{read_only:false; response_revision:26030; number_of_response:1; }","duration":"341.41806ms","start":"2026-07-15T22:36:55.202137Z","end":"2026-07-15T22:36:55.543555Z","steps":["trace[305620689] 'process raft request' (duration: 341.095954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:55.543788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:55.202116Z","time spent":"341.564942ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:55.544264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:55.202131Z","time spent":"341.283807ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:55.545666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.709885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-15T22:36:55.545782Z","caller":"traceutil/trace.go:171","msg":"trace[1021948979] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26030; }","duration":"283.888738ms","start":"2026-07-15T22:36:55.261873Z","end":"2026-07-15T22:36:55.545762Z","steps":["trace[1021948979] 'agreement among raft nodes before linearized reading' (duration: 281.674907ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:55.8364Z","caller":"traceutil/trace.go:171","msg":"trace[1454020432] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"105.885095ms","start":"2026-07-15T22:36:55.730443Z","end":"2026-07-15T22:36:55.836328Z","steps":["trace[1454020432] 'process raft request' (duration: 105.63976ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:56.042921Z","caller":"traceutil/trace.go:171","msg":"trace[1914480314] linearizableReadLoop","detail":"{readStateIndex:26871; appliedIndex:26870; }","duration":"153.806781ms","start":"2026-07-15T22:36:55.889081Z","end":"2026-07-15T22:36:56.042888Z","steps":["trace[1914480314] 'read index received' (duration: 121.442042ms)","trace[1914480314] 'applied index is now lower than readState.Index' (duration: 32.363689ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:56.043381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.28549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:56.043483Z","caller":"traceutil/trace.go:171","msg":"trace[1034568374] transaction","detail":"{read_only:false; response_revision:26033; number_of_response:1; }","duration":"201.230128ms","start":"2026-07-15T22:36:55.842221Z","end":"2026-07-15T22:36:56.043451Z","steps":["trace[1034568374] 'process raft request' (duration: 168.37173ms)","trace[1034568374] 'compare' (duration: 32.165945ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:56.043631Z","caller":"traceutil/trace.go:171","msg":"trace[1736802053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26033; }","duration":"154.453563ms","start":"2026-07-15T22:36:55.889002Z","end":"2026-07-15T22:36:56.043456Z","steps":["trace[1736802053] 'agreement among raft nodes before linearized reading' (duration: 154.2831ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:56.267923Z","caller":"traceutil/trace.go:171","msg":"trace[592439086] linearizableReadLoop","detail":"{readStateIndex:26873; appliedIndex:26872; }","duration":"202.209427ms","start":"2026-07-15T22:36:56.065684Z","end":"2026-07-15T22:36:56.267893Z","steps":["trace[592439086] 'read index received' (duration: 140.64592ms)","trace[592439086] 'applied index is now lower than readState.Index' (duration: 61.562557ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:56.268034Z","caller":"traceutil/trace.go:171","msg":"trace[185040167] transaction","detail":"{read_only:false; response_revision:26035; number_of_response:1; }","duration":"211.842051ms","start":"2026-07-15T22:36:56.056146Z","end":"2026-07-15T22:36:56.267988Z","steps":["trace[185040167] 'process raft request' (duration: 150.246663ms)","trace[185040167] 'compare' (duration: 61.377424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:56.268128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.417091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-15T22:36:56.268163Z","caller":"traceutil/trace.go:171","msg":"trace[1263427885] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26035; }","duration":"202.497752ms","start":"2026-07-15T22:36:56.065653Z","end":"2026-07-15T22:36:56.26815Z","steps":["trace[1263427885] 'agreement among raft nodes before linearized reading' (duration: 202.335579ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:56.555563Z","caller":"traceutil/trace.go:171","msg":"trace[251306523] transaction","detail":"{read_only:false; response_revision:26037; number_of_response:1; }","duration":"160.082251ms","start":"2026-07-15T22:36:56.395444Z","end":"2026-07-15T22:36:56.555527Z","steps":["trace[251306523] 'process raft request' (duration: 109.83967ms)","trace[251306523] 'compare' (duration: 49.977206ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:56.780411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.847242ms","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-07-15T22:36:56.780519Z","caller":"traceutil/trace.go:171","msg":"trace[610850299] 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:26037; }","duration":"108.028096ms","start":"2026-07-15T22:36:56.672465Z","end":"2026-07-15T22:36:56.780493Z","steps":["trace[610850299] 'range keys from in-memory index tree' (duration: 107.759821ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:56.780699Z","caller":"traceutil/trace.go:171","msg":"trace[41720088] transaction","detail":"{read_only:false; response_revision:26038; number_of_response:1; }","duration":"106.470826ms","start":"2026-07-15T22:36:56.674194Z","end":"2026-07-15T22:36:56.780664Z","steps":["trace[41720088] 'process raft request' (duration: 100.119885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:57.464142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.621973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:57.464396Z","caller":"traceutil/trace.go:171","msg":"trace[1865160767] linearizableReadLoop","detail":"{readStateIndex:26879; appliedIndex:26876; }","duration":"293.070224ms","start":"2026-07-15T22:36:57.171307Z","end":"2026-07-15T22:36:57.464377Z","steps":["trace[1865160767] 'read index received' (duration: 1.418736ms)","trace[1865160767] 'applied index is now lower than readState.Index' (duration: 291.650478ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:57.464616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.304128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-15T22:36:57.464657Z","caller":"traceutil/trace.go:171","msg":"trace[1030033539] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26041; }","duration":"293.3753ms","start":"2026-07-15T22:36:57.171268Z","end":"2026-07-15T22:36:57.464643Z","steps":["trace[1030033539] 'agreement among raft nodes before linearized reading' (duration: 293.229977ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:57.464646Z","caller":"traceutil/trace.go:171","msg":"trace[450487403] transaction","detail":"{read_only:false; response_revision:26041; number_of_response:1; }","duration":"320.857486ms","start":"2026-07-15T22:36:57.143735Z","end":"2026-07-15T22:36:57.464593Z","steps":["trace[450487403] 'process raft request' (duration: 320.53229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:57.464877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:57.143725Z","time spent":"321.029359ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:57.464915Z","caller":"traceutil/trace.go:171","msg":"trace[373839187] transaction","detail":"{read_only:false; response_revision:26040; number_of_response:1; }","duration":"321.215933ms","start":"2026-07-15T22:36:57.143657Z","end":"2026-07-15T22:36:57.464873Z","steps":["trace[373839187] 'process raft request' (duration: 218.754903ms)","trace[373839187] 'compare' (duration: 101.368299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:57.465145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:57.143628Z","time spent":"321.404727ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:57.72393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.44602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:57.724151Z","caller":"traceutil/trace.go:171","msg":"trace[919825434] linearizableReadLoop","detail":"{readStateIndex:26880; appliedIndex:26879; }","duration":"217.692383ms","start":"2026-07-15T22:36:57.506436Z","end":"2026-07-15T22:36:57.724128Z","steps":["trace[919825434] 'read index received' (duration: 59.905536ms)","trace[919825434] 'applied index is now lower than readState.Index' (duration: 157.784647ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:57.724305Z","caller":"traceutil/trace.go:171","msg":"trace[1257180531] transaction","detail":"{read_only:false; response_revision:26042; number_of_response:1; }","duration":"254.267083ms","start":"2026-07-15T22:36:57.470019Z","end":"2026-07-15T22:36:57.724286Z","steps":["trace[1257180531] 'process raft request' (duration: 96.345644ms)","trace[1257180531] 'compare' (duration: 157.266367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:57.724556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.256552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-07-15T22:36:57.724555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.772362ms","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-07-15T22:36:57.724603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.121032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:57.724642Z","caller":"traceutil/trace.go:171","msg":"trace[1086128059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26042; }","duration":"175.922145ms","start":"2026-07-15T22:36:57.548705Z","end":"2026-07-15T22:36:57.724627Z","steps":["trace[1086128059] 'agreement among raft nodes before linearized reading' (duration: 175.624179ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:57.724644Z","caller":"traceutil/trace.go:171","msg":"trace[726042834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26042; }","duration":"174.397905ms","start":"2026-07-15T22:36:57.550224Z","end":"2026-07-15T22:36:57.724622Z","steps":["trace[726042834] 'agreement among raft nodes before linearized reading' (duration: 174.210201ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:57.724701Z","caller":"traceutil/trace.go:171","msg":"trace[2080937880] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26042; }","duration":"218.307865ms","start":"2026-07-15T22:36:57.50637Z","end":"2026-07-15T22:36:57.724678Z","steps":["trace[2080937880] 'agreement among raft nodes before linearized reading' (duration: 218.120531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:57.724929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.74482ms","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-07-15T22:36:57.72509Z","caller":"traceutil/trace.go:171","msg":"trace[1889747971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26042; }","duration":"176.015775ms","start":"2026-07-15T22:36:57.549002Z","end":"2026-07-15T22:36:57.725018Z","steps":["trace[1889747971] 'agreement among raft nodes before linearized reading' (duration: 175.508205ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:57.925027Z","caller":"traceutil/trace.go:171","msg":"trace[493915610] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"195.662732ms","start":"2026-07-15T22:36:57.729336Z","end":"2026-07-15T22:36:57.924999Z","steps":["trace[493915610] 'process raft request' (duration: 122.080704ms)","trace[493915610] 'compare' (duration: 73.442245ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:57.949985Z","caller":"traceutil/trace.go:171","msg":"trace[950644079] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"220.234072ms","start":"2026-07-15T22:36:57.729708Z","end":"2026-07-15T22:36:57.949942Z","steps":["trace[950644079] 'process raft request' (duration: 219.871635ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:57.950028Z","caller":"traceutil/trace.go:171","msg":"trace[1135685136] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"220.259723ms","start":"2026-07-15T22:36:57.72974Z","end":"2026-07-15T22:36:57.95Z","steps":["trace[1135685136] 'process raft request' (duration: 219.998278ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:57.950205Z","caller":"traceutil/trace.go:171","msg":"trace[722766669] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"187.398644ms","start":"2026-07-15T22:36:57.762764Z","end":"2026-07-15T22:36:57.950163Z","steps":["trace[722766669] 'process raft request' (duration: 187.018097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:58.293755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.819361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:58.293842Z","caller":"traceutil/trace.go:171","msg":"trace[1571135081] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:26047; }","duration":"107.968364ms","start":"2026-07-15T22:36:58.185846Z","end":"2026-07-15T22:36:58.293814Z","steps":["trace[1571135081] 'count revisions from in-memory index tree' (duration: 107.703249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:58.2939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.617166ms","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-07-15T22:36:58.293968Z","caller":"traceutil/trace.go:171","msg":"trace[263495618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26047; }","duration":"246.753389ms","start":"2026-07-15T22:36:58.047198Z","end":"2026-07-15T22:36:58.293951Z","steps":["trace[263495618] 'range keys from in-memory index tree' (duration: 246.404242ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:58.617298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.789116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T22:36:58.617404Z","caller":"traceutil/trace.go:171","msg":"trace[1262888053] linearizableReadLoop","detail":"{readStateIndex:26887; appliedIndex:26885; }","duration":"277.449365ms","start":"2026-07-15T22:36:58.339936Z","end":"2026-07-15T22:36:58.617385Z","steps":["trace[1262888053] 'read index received' (duration: 16.706639ms)","trace[1262888053] 'applied index is now lower than readState.Index' (duration: 260.741576ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T22:36:58.617622Z","caller":"traceutil/trace.go:171","msg":"trace[1594937548] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"319.850107ms","start":"2026-07-15T22:36:58.297759Z","end":"2026-07-15T22:36:58.617609Z","steps":["trace[1594937548] 'process raft request' (duration: 136.658404ms)","trace[1594937548] 'compare' (duration: 182.624922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:58.617694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:58.297744Z","time spent":"319.915268ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:58.618008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.069237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-07-15T22:36:58.618048Z","caller":"traceutil/trace.go:171","msg":"trace[1910166409] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26049; }","duration":"278.107168ms","start":"2026-07-15T22:36:58.339929Z","end":"2026-07-15T22:36:58.618036Z","steps":["trace[1910166409] 'agreement among raft nodes before linearized reading' (duration: 277.971305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:58.642641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.442702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:58.642808Z","caller":"traceutil/trace.go:171","msg":"trace[794601825] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:26050; }","duration":"236.657525ms","start":"2026-07-15T22:36:58.406123Z","end":"2026-07-15T22:36:58.64278Z","steps":["trace[794601825] 'agreement among raft nodes before linearized reading' (duration: 236.418431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:58.643589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.422761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T22:36:58.644158Z","caller":"traceutil/trace.go:171","msg":"trace[181454415] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26050; }","duration":"146.037703ms","start":"2026-07-15T22:36:58.498104Z","end":"2026-07-15T22:36:58.644141Z","steps":["trace[181454415] 'agreement among raft nodes before linearized reading' (duration: 145.422961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:58.644069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.823941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418604"} {"level":"info","ts":"2026-07-15T22:36:58.644733Z","caller":"traceutil/trace.go:171","msg":"trace[2086372376] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26050; }","duration":"131.534415ms","start":"2026-07-15T22:36:58.513181Z","end":"2026-07-15T22:36:58.644715Z","steps":["trace[2086372376] 'agreement among raft nodes before linearized reading' (duration: 130.639218ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:58.933257Z","caller":"traceutil/trace.go:171","msg":"trace[1370348721] transaction","detail":"{read_only:false; response_revision:26051; number_of_response:1; }","duration":"310.162982ms","start":"2026-07-15T22:36:58.623056Z","end":"2026-07-15T22:36:58.933219Z","steps":["trace[1370348721] 'process raft request' (duration: 309.976228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T22:36:58.933441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:58.623026Z","time spent":"310.310695ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T22:36:59.109138Z","caller":"traceutil/trace.go:171","msg":"trace[2005310546] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"323.325063ms","start":"2026-07-15T22:36:58.785769Z","end":"2026-07-15T22:36:59.109094Z","steps":["trace[2005310546] 'process raft request' (duration: 257.106037ms)","trace[2005310546] 'compare' (duration: 65.543904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:59.109334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T22:36:58.785742Z","time spent":"323.474346ms","remote":"127.0.0.1:44956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T22:36:59.253473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.481883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-15T22:36:59.254043Z","caller":"traceutil/trace.go:171","msg":"trace[595044515] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"273.085881ms","start":"2026-07-15T22:36:58.980914Z","end":"2026-07-15T22:36:59.254Z","steps":["trace[595044515] 'process raft request' (duration: 272.670644ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:59.260867Z","caller":"traceutil/trace.go:171","msg":"trace[414193925] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"225.280039ms","start":"2026-07-15T22:36:59.035562Z","end":"2026-07-15T22:36:59.260842Z","steps":["trace[414193925] 'process raft request' (duration: 218.417537ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:36:59.261203Z","caller":"traceutil/trace.go:171","msg":"trace[733930692] linearizableReadLoop","detail":"{readStateIndex:26893; appliedIndex:26890; }","duration":"127.918296ms","start":"2026-07-15T22:36:59.133272Z","end":"2026-07-15T22:36:59.26119Z","steps":["trace[733930692] 'read index received' (duration: 93.129601ms)","trace[733930692] 'applied index is now lower than readState.Index' (duration: 34.786975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T22:36:59.261363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.08289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1021"} {"level":"info","ts":"2026-07-15T22:36:59.261397Z","caller":"traceutil/trace.go:171","msg":"trace[1014660461] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:26054; }","duration":"128.133341ms","start":"2026-07-15T22:36:59.133254Z","end":"2026-07-15T22:36:59.261387Z","steps":["trace[1014660461] 'agreement among raft nodes before linearized reading' (duration: 128.027729ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:37:10.195824Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22665} {"level":"info","ts":"2026-07-15T22:37:10.31587Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22665,"took":"115.795184ms","hash":1790397096,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":46366720,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-07-15T22:37:10.315937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1790397096,"revision":22665,"compact-revision":20290} {"level":"info","ts":"2026-07-15T22:37:15.27393Z","caller":"traceutil/trace.go:171","msg":"trace[1935827012] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"100.216697ms","start":"2026-07-15T22:37:15.17367Z","end":"2026-07-15T22:37:15.273887Z","steps":["trace[1935827012] 'process raft request' (duration: 99.970702ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:40:03.454967Z","caller":"traceutil/trace.go:171","msg":"trace[467052466] transaction","detail":"{read_only:false; response_revision:27949; number_of_response:1; }","duration":"104.854095ms","start":"2026-07-15T22:40:03.350094Z","end":"2026-07-15T22:40:03.454948Z","steps":["trace[467052466] 'process raft request' (duration: 104.423967ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T22:42:10.202798Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26147} {"level":"info","ts":"2026-07-15T22:42:10.349004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26147,"took":"142.824311ms","hash":1813957978,"current-db-size-bytes":59555840,"current-db-size":"60 MB","current-db-size-in-use-bytes":36188160,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-15T22:42:10.349067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1813957978,"revision":26147,"compact-revision":22665} {"level":"info","ts":"2026-07-15T22:42:14.778242Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T22:42:14.783325Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T22:42:14.783433Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T22:47:10.208827Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29050} {"level":"info","ts":"2026-07-15T22:47:10.346914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29050,"took":"133.971292ms","hash":2238749045,"current-db-size-bytes":59555840,"current-db-size":"60 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T22:47:10.346983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2238749045,"revision":29050,"compact-revision":26147} {"level":"info","ts":"2026-07-15T22:52:10.215952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31308} {"level":"info","ts":"2026-07-15T22:52:10.341277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31308,"took":"121.714786ms","hash":1472736462,"current-db-size-bytes":59555840,"current-db-size":"60 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T22:52:10.341358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1472736462,"revision":31308,"compact-revision":29050} {"level":"info","ts":"2026-07-15T22:57:10.226562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33609} {"level":"info","ts":"2026-07-15T22:57:10.374264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33609,"took":"143.435001ms","hash":2027275949,"current-db-size-bytes":59555840,"current-db-size":"60 MB","current-db-size-in-use-bytes":32612352,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T22:57:10.374323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2027275949,"revision":33609,"compact-revision":31308} {"level":"info","ts":"2026-07-15T23:02:10.233071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35945} {"level":"info","ts":"2026-07-15T23:02:10.384044Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35945,"took":"147.05974ms","hash":2095265881,"current-db-size-bytes":61349888,"current-db-size":"61 MB","current-db-size-in-use-bytes":33275904,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T23:02:10.384124Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2095265881,"revision":35945,"compact-revision":33609} {"level":"info","ts":"2026-07-15T23:03:15.266553Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T23:03:15.271529Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T23:03:15.271613Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-15T23:07:10.240106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38279} {"level":"info","ts":"2026-07-15T23:07:10.364537Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38279,"took":"118.936353ms","hash":2723476609,"current-db-size-bytes":61349888,"current-db-size":"61 MB","current-db-size-in-use-bytes":32686080,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T23:07:10.364613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2723476609,"revision":38279,"compact-revision":35945}