{"level":"warn","ts":"2026-10-14T17:48:24.104659Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:48:24.104749Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.5: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://162.253.55.5:2380","--initial-cluster=instance=https://162.253.55.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.5: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-10-14T17:48:24.104806Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-14T17:48:24.104814Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-10-14T17:48:24.104833Z","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-10-14T17:48:24.10539Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"]} {"level":"info","ts":"2026-10-14T17:48:24.105475Z","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://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.5: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-10-14T17:48:24.108406Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.691829ms"} {"level":"info","ts":"2026-10-14T17:48:24.115216Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b512f7d8ac93be25","cluster-id":"e1953d9a3e7368e5"} {"level":"info","ts":"2026-10-14T17:48:24.115311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=()"} {"level":"info","ts":"2026-10-14T17:48:24.115336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 0"} {"level":"info","ts":"2026-10-14T17:48:24.115355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b512f7d8ac93be25 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-14T17:48:24.115367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became follower at term 1"} {"level":"info","ts":"2026-10-14T17:48:24.115425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"warn","ts":"2026-10-14T17:48:24.121301Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-14T17:48:24.123504Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-14T17:48:24.125224Z","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-10-14T17:48:24.128394Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b512f7d8ac93be25","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-14T17:48:24.128644Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b512f7d8ac93be25","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-14T17:48:24.128775Z","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-10-14T17:48:24.128923Z","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-10-14T17:48:24.128956Z","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-10-14T17:48:24.129605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 switched to configuration voters=(13047763580426108453)"} {"level":"info","ts":"2026-10-14T17:48:24.130391Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","added-peer-id":"b512f7d8ac93be25","added-peer-peer-urls":["https://162.253.55.5:2380"]} {"level":"info","ts":"2026-10-14T17:48:24.132704Z","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-10-14T17:48:24.132994Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b512f7d8ac93be25","initial-advertise-peer-urls":["https://162.253.55.5:2380"],"listen-peer-urls":["https://162.253.55.5:2380"],"advertise-client-urls":["https://162.253.55.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-14T17:48:24.133031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-14T17:48:24.134282Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-10-14T17:48:24.13431Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.5:2380"} {"level":"info","ts":"2026-10-14T17:48:24.615966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 is starting a new election at term 1"} {"level":"info","ts":"2026-10-14T17:48:24.616046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-14T17:48:24.616069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgPreVoteResp from b512f7d8ac93be25 at term 1"} {"level":"info","ts":"2026-10-14T17:48:24.616084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became candidate at term 2"} {"level":"info","ts":"2026-10-14T17:48:24.61609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 received MsgVoteResp from b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-10-14T17:48:24.616101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b512f7d8ac93be25 became leader at term 2"} {"level":"info","ts":"2026-10-14T17:48:24.61611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b512f7d8ac93be25 elected leader b512f7d8ac93be25 at term 2"} {"level":"info","ts":"2026-10-14T17:48:24.617058Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:48:24.618435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:48:24.618489Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-14T17:48:24.618433Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b512f7d8ac93be25","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.5:2379]}","request-path":"/0/members/b512f7d8ac93be25/attributes","cluster-id":"e1953d9a3e7368e5","publish-timeout":"7s"} {"level":"info","ts":"2026-10-14T17:48:24.61926Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-14T17:48:24.619354Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-14T17:48:24.62043Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-14T17:48:24.620992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.5:2379"} {"level":"info","ts":"2026-10-14T17:48:24.625267Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e1953d9a3e7368e5","local-member-id":"b512f7d8ac93be25","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:48:24.625348Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-14T17:48:24.625381Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-10-14T17:48:33.990015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.513897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:48:33.990171Z","caller":"traceutil/trace.go:171","msg":"trace[1198999089] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:292; }","duration":"130.739481ms","start":"2026-10-14T17:48:33.859405Z","end":"2026-10-14T17:48:33.990145Z","steps":["trace[1198999089] 'range keys from in-memory index tree' (duration: 130.433845ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:54.506533Z","caller":"traceutil/trace.go:171","msg":"trace[1092299205] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"123.088434ms","start":"2026-10-14T17:48:54.383411Z","end":"2026-10-14T17:48:54.506499Z","steps":["trace[1092299205] 'process raft request' (duration: 122.90741ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:54.506817Z","caller":"traceutil/trace.go:171","msg":"trace[1179630625] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"125.473737ms","start":"2026-10-14T17:48:54.381314Z","end":"2026-10-14T17:48:54.506788Z","steps":["trace[1179630625] 'process raft request' (duration: 110.05165ms)","trace[1179630625] 'compare' (duration: 14.821653ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:48:54.506897Z","caller":"traceutil/trace.go:171","msg":"trace[135967012] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"123.005752ms","start":"2026-10-14T17:48:54.383852Z","end":"2026-10-14T17:48:54.506858Z","steps":["trace[135967012] 'process raft request' (duration: 122.533173ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:54.50709Z","caller":"traceutil/trace.go:171","msg":"trace[1590652844] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"123.199468ms","start":"2026-10-14T17:48:54.383857Z","end":"2026-10-14T17:48:54.507057Z","steps":["trace[1590652844] 'process raft request' (duration: 122.568843ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:48:54.507399Z","caller":"traceutil/trace.go:171","msg":"trace[702397951] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"123.366721ms","start":"2026-10-14T17:48:54.383997Z","end":"2026-10-14T17:48:54.507364Z","steps":["trace[702397951] 'process raft request' (duration: 122.927411ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:15.415651Z","caller":"traceutil/trace.go:171","msg":"trace[325171626] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"167.999425ms","start":"2026-10-14T17:49:15.247609Z","end":"2026-10-14T17:49:15.415608Z","steps":["trace[325171626] 'process raft request' (duration: 167.217378ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:49:15.757831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.289215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:49:15.757951Z","caller":"traceutil/trace.go:171","msg":"trace[1036387068] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"269.579942ms","start":"2026-10-14T17:49:15.488344Z","end":"2026-10-14T17:49:15.757924Z","steps":["trace[1036387068] 'process raft request' (duration: 130.326578ms)","trace[1036387068] 'compare' (duration: 137.829574ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:49:16.35157Z","caller":"traceutil/trace.go:171","msg":"trace[499715453] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"175.376126ms","start":"2026-10-14T17:49:16.176164Z","end":"2026-10-14T17:49:16.35154Z","steps":["trace[499715453] 'process raft request' (duration: 173.69399ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:49:32.423865Z","caller":"traceutil/trace.go:171","msg":"trace[1766083335] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"156.214567ms","start":"2026-10-14T17:49:32.267626Z","end":"2026-10-14T17:49:32.423841Z","steps":["trace[1766083335] 'process raft request' (duration: 156.035892ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:27.806812Z","caller":"traceutil/trace.go:171","msg":"trace[18894266] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"159.462304ms","start":"2026-10-14T17:50:27.647291Z","end":"2026-10-14T17:50:27.806754Z","steps":["trace[18894266] 'process raft request' (duration: 159.277319ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:50:27.813843Z","caller":"traceutil/trace.go:171","msg":"trace[1382051207] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"131.735873ms","start":"2026-10-14T17:50:27.682082Z","end":"2026-10-14T17:50:27.813818Z","steps":["trace[1382051207] 'process raft request' (duration: 131.57758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:51.890966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.270172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7cd437b4-242f-4b0c-b0c3-15e9b8d4f0a3\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-10-14T17:50:51.891071Z","caller":"traceutil/trace.go:171","msg":"trace[202585407] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7cd437b4-242f-4b0c-b0c3-15e9b8d4f0a3; range_end:; response_count:1; response_revision:1838; }","duration":"186.424376ms","start":"2026-10-14T17:50:51.704629Z","end":"2026-10-14T17:50:51.891053Z","steps":["trace[202585407] 'range keys from in-memory index tree' (duration: 186.047226ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:50:52.122918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.783431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:50:52.123141Z","caller":"traceutil/trace.go:171","msg":"trace[1560331301] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1893; }","duration":"191.741292ms","start":"2026-10-14T17:50:51.931374Z","end":"2026-10-14T17:50:52.123115Z","steps":["trace[1560331301] 'read index received' (duration: 66.379337ms)","trace[1560331301] 'applied index is now lower than readState.Index' (duration: 125.359695ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:50:52.123217Z","caller":"traceutil/trace.go:171","msg":"trace[1365842137] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"199.744957ms","start":"2026-10-14T17:50:51.923401Z","end":"2026-10-14T17:50:52.123146Z","steps":["trace[1365842137] 'process raft request' (duration: 74.453905ms)","trace[1365842137] 'compare' (duration: 124.652739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:50:52.123316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.273184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T17:50:52.123349Z","caller":"traceutil/trace.go:171","msg":"trace[603606622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1840; }","duration":"192.345246ms","start":"2026-10-14T17:50:51.930994Z","end":"2026-10-14T17:50:52.123339Z","steps":["trace[603606622] 'agreement among raft nodes before linearized reading' (duration: 192.194703ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:34.552713Z","caller":"traceutil/trace.go:171","msg":"trace[824383246] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"255.51427ms","start":"2026-10-14T17:51:34.297177Z","end":"2026-10-14T17:51:34.552691Z","steps":["trace[824383246] 'process raft request' (duration: 255.347107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:34.552989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.754962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-14T17:51:34.553266Z","caller":"traceutil/trace.go:171","msg":"trace[639761876] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2250; }","duration":"145.451266ms","start":"2026-10-14T17:51:34.407204Z","end":"2026-10-14T17:51:34.552656Z","steps":["trace[639761876] 'read index received' (duration: 145.229651ms)","trace[639761876] 'applied index is now lower than readState.Index' (duration: 220.814µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:51:34.553219Z","caller":"traceutil/trace.go:171","msg":"trace[1113285870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2179; }","duration":"146.015467ms","start":"2026-10-14T17:51:34.407161Z","end":"2026-10-14T17:51:34.553176Z","steps":["trace[1113285870] 'agreement among raft nodes before linearized reading' (duration: 145.574328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:34.553497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.541871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T17:51:34.553564Z","caller":"traceutil/trace.go:171","msg":"trace[566540632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2179; }","duration":"124.659453ms","start":"2026-10-14T17:51:34.428888Z","end":"2026-10-14T17:51:34.553547Z","steps":["trace[566540632] 'agreement among raft nodes before linearized reading' (duration: 124.547341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T17:51:34.691465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.962923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T17:51:34.691627Z","caller":"traceutil/trace.go:171","msg":"trace[235206188] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"133.080706ms","start":"2026-10-14T17:51:34.55853Z","end":"2026-10-14T17:51:34.69161Z","steps":["trace[235206188] 'process raft request' (duration: 133.025775ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:51:34.691778Z","caller":"traceutil/trace.go:171","msg":"trace[13309506] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"133.28213ms","start":"2026-10-14T17:51:34.558385Z","end":"2026-10-14T17:51:34.691667Z","steps":["trace[13309506] 'process raft request' (duration: 18.034171ms)","trace[13309506] 'compare' (duration: 114.805249ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T17:55:23.226116Z","caller":"traceutil/trace.go:171","msg":"trace[2050879672] transaction","detail":"{read_only:false; response_revision:4605; number_of_response:1; }","duration":"155.332265ms","start":"2026-10-14T17:55:23.070752Z","end":"2026-10-14T17:55:23.226084Z","steps":["trace[2050879672] 'process raft request' (duration: 155.084759ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:23.233658Z","caller":"traceutil/trace.go:171","msg":"trace[1384205797] transaction","detail":"{read_only:false; response_revision:4606; number_of_response:1; }","duration":"146.590193ms","start":"2026-10-14T17:55:23.087032Z","end":"2026-10-14T17:55:23.233622Z","steps":["trace[1384205797] 'process raft request' (duration: 146.302096ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:31.02366Z","caller":"traceutil/trace.go:171","msg":"trace[281119927] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"101.71882ms","start":"2026-10-14T17:55:30.921909Z","end":"2026-10-14T17:55:31.023628Z","steps":["trace[281119927] 'process raft request' (duration: 12.997405ms)","trace[281119927] 'compare' (duration: 88.553041ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T17:55:40.483297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.41499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-t65vw\" ","response":"range_response_count:1 size:7811"} {"level":"info","ts":"2026-10-14T17:55:40.483398Z","caller":"traceutil/trace.go:171","msg":"trace[1297196269] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-t65vw; range_end:; response_count:1; response_revision:4926; }","duration":"238.567203ms","start":"2026-10-14T17:55:40.244808Z","end":"2026-10-14T17:55:40.483375Z","steps":["trace[1297196269] 'range keys from in-memory index tree' (duration: 238.199885ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:55:47.468418Z","caller":"traceutil/trace.go:171","msg":"trace[671475057] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"125.630214ms","start":"2026-10-14T17:55:47.342738Z","end":"2026-10-14T17:55:47.468368Z","steps":["trace[671475057] 'process raft request' (duration: 125.292768ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T17:58:24.647792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-10-14T17:58:24.733266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"83.978813ms","hash":1681666321,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":36204544,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T17:58:24.733372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1681666321,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-10-14T17:58:34.023681Z","caller":"traceutil/trace.go:171","msg":"trace[1573691305] transaction","detail":"{read_only:false; response_revision:6537; number_of_response:1; }","duration":"112.725274ms","start":"2026-10-14T17:58:33.910904Z","end":"2026-10-14T17:58:34.023629Z","steps":["trace[1573691305] 'process raft request' (duration: 70.917341ms)","trace[1573691305] 'compare' (duration: 41.435574ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:01:48.165917Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002204.wal"} {"level":"info","ts":"2026-10-14T18:01:52.598193Z","caller":"traceutil/trace.go:171","msg":"trace[305602737] transaction","detail":"{read_only:false; response_revision:8376; number_of_response:1; }","duration":"190.446895ms","start":"2026-10-14T18:01:52.40772Z","end":"2026-10-14T18:01:52.598167Z","steps":["trace[305602737] 'process raft request' (duration: 189.917505ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:01:52.710556Z","caller":"traceutil/trace.go:171","msg":"trace[663928052] transaction","detail":"{read_only:false; response_revision:8378; number_of_response:1; }","duration":"104.915762ms","start":"2026-10-14T18:01:52.605619Z","end":"2026-10-14T18:01:52.710535Z","steps":["trace[663928052] 'process raft request' (duration: 104.821649ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:01:52.710575Z","caller":"traceutil/trace.go:171","msg":"trace[907432625] transaction","detail":"{read_only:false; response_revision:8377; number_of_response:1; }","duration":"107.751303ms","start":"2026-10-14T18:01:52.6028Z","end":"2026-10-14T18:01:52.710551Z","steps":["trace[907432625] 'process raft request' (duration: 104.637786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:04.592722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.897798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:02:04.592821Z","caller":"traceutil/trace.go:171","msg":"trace[334864754] linearizableReadLoop","detail":"{readStateIndex:8828; appliedIndex:8827; }","duration":"404.034392ms","start":"2026-10-14T18:02:04.18877Z","end":"2026-10-14T18:02:04.592805Z","steps":["trace[334864754] 'read index received' (duration: 64.96117ms)","trace[334864754] 'applied index is now lower than readState.Index' (duration: 339.072212ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:04.592911Z","caller":"traceutil/trace.go:171","msg":"trace[1730433831] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"506.038233ms","start":"2026-10-14T18:02:04.086808Z","end":"2026-10-14T18:02:04.592846Z","steps":["trace[1730433831] 'process raft request' (duration: 166.9273ms)","trace[1730433831] 'compare' (duration: 338.773925ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:02:04.592996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.911344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:02:04.59304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.451746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-14T18:02:04.593055Z","caller":"traceutil/trace.go:171","msg":"trace[1944178814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8452; }","duration":"164.010397ms","start":"2026-10-14T18:02:04.429027Z","end":"2026-10-14T18:02:04.593038Z","steps":["trace[1944178814] 'agreement among raft nodes before linearized reading' (duration: 163.916264ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:04.593092Z","caller":"traceutil/trace.go:171","msg":"trace[367399207] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8452; }","duration":"400.538578ms","start":"2026-10-14T18:02:04.192537Z","end":"2026-10-14T18:02:04.593075Z","steps":["trace[367399207] 'agreement among raft nodes before linearized reading' (duration: 400.363924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:04.593157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:02:04.192514Z","time spent":"400.632521ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T18:02:04.593277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:02:04.08679Z","time spent":"506.249056ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:02:04.593286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.41142ms","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:5"} {"level":"info","ts":"2026-10-14T18:02:04.593389Z","caller":"traceutil/trace.go:171","msg":"trace[978079810] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8452; }","duration":"404.682637ms","start":"2026-10-14T18:02:04.188685Z","end":"2026-10-14T18:02:04.593367Z","steps":["trace[978079810] 'agreement among raft nodes before linearized reading' (duration: 404.178186ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:04.593635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:02:04.188645Z","time spent":"404.81613ms","remote":"127.0.0.1:35876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:02:05.152921Z","caller":"traceutil/trace.go:171","msg":"trace[1213050598] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"185.280795ms","start":"2026-10-14T18:02:04.967607Z","end":"2026-10-14T18:02:05.152888Z","steps":["trace[1213050598] 'process raft request' (duration: 185.220774ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:05.153168Z","caller":"traceutil/trace.go:171","msg":"trace[850073590] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"313.580922ms","start":"2026-10-14T18:02:04.839512Z","end":"2026-10-14T18:02:05.153093Z","steps":["trace[850073590] 'process raft request' (duration: 313.250614ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:05.153261Z","caller":"traceutil/trace.go:171","msg":"trace[1875144363] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"313.801076ms","start":"2026-10-14T18:02:04.83942Z","end":"2026-10-14T18:02:05.153221Z","steps":["trace[1875144363] 'process raft request' (duration: 228.739272ms)","trace[1875144363] 'compare' (duration: 84.473332ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:05.153264Z","caller":"traceutil/trace.go:171","msg":"trace[1633205785] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8832; }","duration":"267.376477ms","start":"2026-10-14T18:02:04.885786Z","end":"2026-10-14T18:02:05.153162Z","steps":["trace[1633205785] 'read index received' (duration: 182.390594ms)","trace[1633205785] 'applied index is now lower than readState.Index' (duration: 84.981692ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:02:05.153353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:02:04.839498Z","time spent":"313.764505ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:02:05.153405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:02:04.839404Z","time spent":"313.903489ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:02:05.153436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.653802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tv9pz\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-10-14T18:02:05.153476Z","caller":"traceutil/trace.go:171","msg":"trace[71128955] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tv9pz; range_end:; response_count:1; response_revision:8459; }","duration":"267.747303ms","start":"2026-10-14T18:02:04.885717Z","end":"2026-10-14T18:02:05.153465Z","steps":["trace[71128955] 'agreement among raft nodes before linearized reading' (duration: 267.58063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:05.153678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.237469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11492"} {"level":"info","ts":"2026-10-14T18:02:05.153766Z","caller":"traceutil/trace.go:171","msg":"trace[1371262279] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8459; }","duration":"245.339151ms","start":"2026-10-14T18:02:04.908395Z","end":"2026-10-14T18:02:05.153734Z","steps":["trace[1371262279] 'agreement among raft nodes before linearized reading' (duration: 245.017524ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:05.368993Z","caller":"traceutil/trace.go:171","msg":"trace[1305837663] linearizableReadLoop","detail":"{readStateIndex:8837; appliedIndex:8836; }","duration":"134.560792ms","start":"2026-10-14T18:02:05.23441Z","end":"2026-10-14T18:02:05.36897Z","steps":["trace[1305837663] 'read index received' (duration: 134.355888ms)","trace[1305837663] 'applied index is now lower than readState.Index' (duration: 203.894µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:05.369086Z","caller":"traceutil/trace.go:171","msg":"trace[1228922745] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"155.179857ms","start":"2026-10-14T18:02:05.213853Z","end":"2026-10-14T18:02:05.369033Z","steps":["trace[1228922745] 'process raft request' (duration: 154.940082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:05.369262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.848438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-14T18:02:05.369304Z","caller":"traceutil/trace.go:171","msg":"trace[1022799719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8461; }","duration":"134.97095ms","start":"2026-10-14T18:02:05.234323Z","end":"2026-10-14T18:02:05.369294Z","steps":["trace[1022799719] 'agreement among raft nodes before linearized reading' (duration: 134.774046ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:20.938025Z","caller":"traceutil/trace.go:171","msg":"trace[1560800933] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"100.832364ms","start":"2026-10-14T18:02:20.837162Z","end":"2026-10-14T18:02:20.937995Z","steps":["trace[1560800933] 'process raft request' (duration: 100.63905ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:31.411967Z","caller":"traceutil/trace.go:171","msg":"trace[1865984371] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"106.310742ms","start":"2026-10-14T18:02:31.305621Z","end":"2026-10-14T18:02:31.411932Z","steps":["trace[1865984371] 'process raft request' (duration: 105.769771ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:46.213681Z","caller":"traceutil/trace.go:171","msg":"trace[1824783418] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"177.085508ms","start":"2026-10-14T18:02:46.036572Z","end":"2026-10-14T18:02:46.213657Z","steps":["trace[1824783418] 'process raft request' (duration: 176.931134ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:46.437662Z","caller":"traceutil/trace.go:171","msg":"trace[33987299] transaction","detail":"{read_only:false; response_revision:8828; number_of_response:1; }","duration":"134.439449ms","start":"2026-10-14T18:02:46.303192Z","end":"2026-10-14T18:02:46.437631Z","steps":["trace[33987299] 'process raft request' (duration: 134.234945ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:47.144324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.702655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-14T18:02:47.144493Z","caller":"traceutil/trace.go:171","msg":"trace[2137324138] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"205.848179ms","start":"2026-10-14T18:02:46.938629Z","end":"2026-10-14T18:02:47.144477Z","steps":["trace[2137324138] 'process raft request' (duration: 205.787928ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:47.144709Z","caller":"traceutil/trace.go:171","msg":"trace[1192391476] linearizableReadLoop","detail":"{readStateIndex:9229; appliedIndex:9228; }","duration":"290.028484ms","start":"2026-10-14T18:02:46.854629Z","end":"2026-10-14T18:02:47.144658Z","steps":["trace[1192391476] 'read index received' (duration: 26.802768ms)","trace[1192391476] 'applied index is now lower than readState.Index' (duration: 263.218586ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:02:47.144992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.352222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T18:02:47.145032Z","caller":"traceutil/trace.go:171","msg":"trace[1494275806] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8833; }","duration":"290.451193ms","start":"2026-10-14T18:02:46.854569Z","end":"2026-10-14T18:02:47.145021Z","steps":["trace[1494275806] 'agreement among raft nodes before linearized reading' (duration: 290.28392ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:47.144769Z","caller":"traceutil/trace.go:171","msg":"trace[446228155] transaction","detail":"{read_only:false; response_revision:8832; number_of_response:1; }","duration":"360.278018ms","start":"2026-10-14T18:02:46.784466Z","end":"2026-10-14T18:02:47.144744Z","steps":["trace[446228155] 'process raft request' (duration: 97.074003ms)","trace[446228155] 'compare' (duration: 262.560772ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:02:47.145422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:02:46.784446Z","time spent":"360.8196ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:02:47.213405Z","caller":"traceutil/trace.go:171","msg":"trace[1351731723] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"200.007072ms","start":"2026-10-14T18:02:47.013353Z","end":"2026-10-14T18:02:47.21336Z","steps":["trace[1351731723] 'process raft request' (duration: 199.785617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:47.213447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.408298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-14T18:02:47.213486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.63273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-14T18:02:47.21352Z","caller":"traceutil/trace.go:171","msg":"trace[1212033882] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8834; }","duration":"147.530801ms","start":"2026-10-14T18:02:47.065972Z","end":"2026-10-14T18:02:47.213503Z","steps":["trace[1212033882] 'agreement among raft nodes before linearized reading' (duration: 147.331657ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:47.213543Z","caller":"traceutil/trace.go:171","msg":"trace[1588346050] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8834; }","duration":"145.712612ms","start":"2026-10-14T18:02:47.067814Z","end":"2026-10-14T18:02:47.213526Z","steps":["trace[1588346050] 'agreement among raft nodes before linearized reading' (duration: 145.558358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:02:47.213977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.052902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-10-14T18:02:47.214176Z","caller":"traceutil/trace.go:171","msg":"trace[968586060] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8834; }","duration":"141.249546ms","start":"2026-10-14T18:02:47.07291Z","end":"2026-10-14T18:02:47.21416Z","steps":["trace[968586060] 'agreement among raft nodes before linearized reading' (duration: 140.876959ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:02:47.28134Z","caller":"traceutil/trace.go:171","msg":"trace[341536333] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"130.638206ms","start":"2026-10-14T18:02:47.150672Z","end":"2026-10-14T18:02:47.28131Z","steps":["trace[341536333] 'process raft request' (duration: 96.861098ms)","trace[341536333] 'compare' (duration: 33.547274ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:02:47.281704Z","caller":"traceutil/trace.go:171","msg":"trace[400473748] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8835; }","duration":"129.982593ms","start":"2026-10-14T18:02:47.151699Z","end":"2026-10-14T18:02:47.281682Z","steps":["trace[400473748] 'process raft request' (duration: 129.571253ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:03:24.656731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6465} {"level":"info","ts":"2026-10-14T18:03:24.776814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6465,"took":"117.036795ms","hash":64441388,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":25931776,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T18:03:24.776882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":64441388,"revision":6465,"compact-revision":2980} {"level":"info","ts":"2026-10-14T18:03:55.987348Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:03:55.995101Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-14T18:03:55.995226Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-14T18:04:01.795525Z","caller":"traceutil/trace.go:171","msg":"trace[611382644] linearizableReadLoop","detail":"{readStateIndex:10042; appliedIndex:10041; }","duration":"126.806735ms","start":"2026-10-14T18:04:01.668693Z","end":"2026-10-14T18:04:01.795499Z","steps":["trace[611382644] 'read index received' (duration: 126.6364ms)","trace[611382644] 'applied index is now lower than readState.Index' (duration: 169.155µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:04:01.795593Z","caller":"traceutil/trace.go:171","msg":"trace[1582966554] transaction","detail":"{read_only:false; response_revision:9608; number_of_response:1; }","duration":"166.765546ms","start":"2026-10-14T18:04:01.628796Z","end":"2026-10-14T18:04:01.795561Z","steps":["trace[1582966554] 'process raft request' (duration: 166.550652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:01.795757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-14T18:04:01.795798Z","caller":"traceutil/trace.go:171","msg":"trace[196297799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9608; }","duration":"127.137651ms","start":"2026-10-14T18:04:01.66865Z","end":"2026-10-14T18:04:01.795788Z","steps":["trace[196297799] 'agreement among raft nodes before linearized reading' (duration: 126.990089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:01.795907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.929219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-14T18:04:01.795985Z","caller":"traceutil/trace.go:171","msg":"trace[1588536854] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9608; }","duration":"109.035052ms","start":"2026-10-14T18:04:01.686932Z","end":"2026-10-14T18:04:01.795967Z","steps":["trace[1588536854] 'agreement among raft nodes before linearized reading' (duration: 108.837887ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:01.795994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.382231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T18:04:01.796362Z","caller":"traceutil/trace.go:171","msg":"trace[633607982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9608; }","duration":"117.800691ms","start":"2026-10-14T18:04:01.678545Z","end":"2026-10-14T18:04:01.796346Z","steps":["trace[633607982] 'agreement among raft nodes before linearized reading' (duration: 117.217409ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:02.255982Z","caller":"traceutil/trace.go:171","msg":"trace[1107849118] transaction","detail":"{read_only:false; response_revision:9609; number_of_response:1; }","duration":"455.524333ms","start":"2026-10-14T18:04:01.800434Z","end":"2026-10-14T18:04:02.255959Z","steps":["trace[1107849118] 'process raft request' (duration: 455.383131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.256343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.800412Z","time spent":"455.635245ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:02.277115Z","caller":"traceutil/trace.go:171","msg":"trace[456900937] transaction","detail":"{read_only:false; response_revision:9610; number_of_response:1; }","duration":"475.599676ms","start":"2026-10-14T18:04:01.801491Z","end":"2026-10-14T18:04:02.27709Z","steps":["trace[456900937] 'process raft request' (duration: 475.428512ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.277304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.801467Z","time spent":"475.757149ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:04:02.277413Z","caller":"traceutil/trace.go:171","msg":"trace[916309286] transaction","detail":"{read_only:false; response_revision:9611; number_of_response:1; }","duration":"475.84995ms","start":"2026-10-14T18:04:01.801541Z","end":"2026-10-14T18:04:02.277391Z","steps":["trace[916309286] 'process raft request' (duration: 475.512764ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:02.277499Z","caller":"traceutil/trace.go:171","msg":"trace[320184246] linearizableReadLoop","detail":"{readStateIndex:10045; appliedIndex:10042; }","duration":"396.763846ms","start":"2026-10-14T18:04:01.880725Z","end":"2026-10-14T18:04:02.277489Z","steps":["trace[320184246] 'read index received' (duration: 375.107629ms)","trace[320184246] 'applied index is now lower than readState.Index' (duration: 21.655357ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:04:02.27755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.801515Z","time spent":"475.961973ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:04:02.277739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.458054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tv9pz\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-10-14T18:04:02.277777Z","caller":"traceutil/trace.go:171","msg":"trace[2146999596] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tv9pz; range_end:; response_count:1; response_revision:9612; }","duration":"303.541295ms","start":"2026-10-14T18:04:01.974226Z","end":"2026-10-14T18:04:02.277767Z","steps":["trace[2146999596] 'agreement among raft nodes before linearized reading' (duration: 303.400551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.277804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.9742Z","time spent":"303.596967ms","remote":"127.0.0.1:55554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6540,"request content":"key:\"/registry/pods/openstack/keepalived-tv9pz\" "} {"level":"warn","ts":"2026-10-14T18:04:02.277991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.617107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-10-14T18:04:02.278038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.939793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-14T18:04:02.278042Z","caller":"traceutil/trace.go:171","msg":"trace[748162087] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:9612; }","duration":"111.70313ms","start":"2026-10-14T18:04:02.166325Z","end":"2026-10-14T18:04:02.278028Z","steps":["trace[748162087] 'agreement among raft nodes before linearized reading' (duration: 111.552826ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:04:02.278065Z","caller":"traceutil/trace.go:171","msg":"trace[1060935748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9612; }","duration":"271.003714ms","start":"2026-10-14T18:04:02.007053Z","end":"2026-10-14T18:04:02.278057Z","steps":["trace[1060935748] 'agreement among raft nodes before linearized reading' (duration: 270.918772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.2782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.463132ms","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-10-14T18:04:02.278291Z","caller":"traceutil/trace.go:171","msg":"trace[1949622727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9612; }","duration":"397.582083ms","start":"2026-10-14T18:04:01.880683Z","end":"2026-10-14T18:04:02.278265Z","steps":["trace[1949622727] 'agreement among raft nodes before linearized reading' (duration: 397.353768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:04:02.278335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:04:01.880664Z","time spent":"397.660814ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-14T18:07:37.793313Z","caller":"traceutil/trace.go:171","msg":"trace[2068641330] transaction","detail":"{read_only:false; response_revision:11772; number_of_response:1; }","duration":"202.761766ms","start":"2026-10-14T18:07:37.590527Z","end":"2026-10-14T18:07:37.793289Z","steps":["trace[2068641330] 'process raft request' (duration: 202.513231ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:37.7935Z","caller":"traceutil/trace.go:171","msg":"trace[1386757863] linearizableReadLoop","detail":"{readStateIndex:12300; appliedIndex:12298; }","duration":"134.320515ms","start":"2026-10-14T18:07:37.659139Z","end":"2026-10-14T18:07:37.793459Z","steps":["trace[1386757863] 'read index received' (duration: 133.947977ms)","trace[1386757863] 'applied index is now lower than readState.Index' (duration: 370.548µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:07:37.793542Z","caller":"traceutil/trace.go:171","msg":"trace[1386100188] transaction","detail":"{read_only:false; response_revision:11773; number_of_response:1; }","duration":"202.96793ms","start":"2026-10-14T18:07:37.590552Z","end":"2026-10-14T18:07:37.79352Z","steps":["trace[1386100188] 'process raft request' (duration: 202.693684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:07:37.793705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.54374ms","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-10-14T18:07:37.793803Z","caller":"traceutil/trace.go:171","msg":"trace[1216184279] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11773; }","duration":"134.711013ms","start":"2026-10-14T18:07:37.659072Z","end":"2026-10-14T18:07:37.793783Z","steps":["trace[1216184279] 'agreement among raft nodes before linearized reading' (duration: 134.550649ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:07:37.793906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.650362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:07:37.793958Z","caller":"traceutil/trace.go:171","msg":"trace[1057383677] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11773; }","duration":"134.743785ms","start":"2026-10-14T18:07:37.659203Z","end":"2026-10-14T18:07:37.793946Z","steps":["trace[1057383677] 'agreement among raft nodes before linearized reading' (duration: 134.641602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:07:37.794054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.525435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:07:37.794074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.580997ms","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-10-14T18:07:37.794115Z","caller":"traceutil/trace.go:171","msg":"trace[1459910840] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11773; }","duration":"131.575247ms","start":"2026-10-14T18:07:37.662507Z","end":"2026-10-14T18:07:37.794082Z","steps":["trace[1459910840] 'agreement among raft nodes before linearized reading' (duration: 131.520346ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:37.794129Z","caller":"traceutil/trace.go:171","msg":"trace[1335903878] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11773; }","duration":"131.658239ms","start":"2026-10-14T18:07:37.662463Z","end":"2026-10-14T18:07:37.794121Z","steps":["trace[1335903878] 'agreement among raft nodes before linearized reading' (duration: 131.587247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:07:37.794194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.905541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5076"} {"level":"warn","ts":"2026-10-14T18:07:37.794241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.200218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-14T18:07:37.79424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.901968ms","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-10-14T18:07:37.794256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.011346ms","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-10-14T18:07:37.794298Z","caller":"traceutil/trace.go:171","msg":"trace[1488055619] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11773; }","duration":"135.004059ms","start":"2026-10-14T18:07:37.659276Z","end":"2026-10-14T18:07:37.79428Z","steps":["trace[1488055619] 'agreement among raft nodes before linearized reading' (duration: 134.922878ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:37.79432Z","caller":"traceutil/trace.go:171","msg":"trace[95135198] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11773; }","duration":"132.097088ms","start":"2026-10-14T18:07:37.662207Z","end":"2026-10-14T18:07:37.794304Z","steps":["trace[95135198] 'agreement among raft nodes before linearized reading' (duration: 131.876233ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:37.794276Z","caller":"traceutil/trace.go:171","msg":"trace[1796262952] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11773; }","duration":"131.232799ms","start":"2026-10-14T18:07:37.663035Z","end":"2026-10-14T18:07:37.794268Z","steps":["trace[1796262952] 'agreement among raft nodes before linearized reading' (duration: 131.190759ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:07:37.794403Z","caller":"traceutil/trace.go:171","msg":"trace[478718203] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11773; }","duration":"130.113066ms","start":"2026-10-14T18:07:37.664145Z","end":"2026-10-14T18:07:37.794258Z","steps":["trace[478718203] 'agreement among raft nodes before linearized reading' (duration: 129.717248ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:07:37.794207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.908391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5076"} {"level":"info","ts":"2026-10-14T18:07:37.794511Z","caller":"traceutil/trace.go:171","msg":"trace[1830025301] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11773; }","duration":"129.247278ms","start":"2026-10-14T18:07:37.665254Z","end":"2026-10-14T18:07:37.794501Z","steps":["trace[1830025301] 'agreement among raft nodes before linearized reading' (duration: 128.788208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:07:37.794229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.530035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:07:37.794617Z","caller":"traceutil/trace.go:171","msg":"trace[1908176022] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11773; }","duration":"130.918043ms","start":"2026-10-14T18:07:37.663675Z","end":"2026-10-14T18:07:37.794593Z","steps":["trace[1908176022] 'agreement among raft nodes before linearized reading' (duration: 130.534815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:07:37.794207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.345661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:07:37.794856Z","caller":"traceutil/trace.go:171","msg":"trace[721687293] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11773; }","duration":"130.984865ms","start":"2026-10-14T18:07:37.663834Z","end":"2026-10-14T18:07:37.794819Z","steps":["trace[721687293] 'agreement among raft nodes before linearized reading' (duration: 130.349852ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:08:24.664692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9234} {"level":"info","ts":"2026-10-14T18:08:24.75533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9234,"took":"88.218349ms","hash":2865835329,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":28205056,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-14T18:08:24.755488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2865835329,"revision":9234,"compact-revision":6465} {"level":"info","ts":"2026-10-14T18:13:24.67424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12169} {"level":"info","ts":"2026-10-14T18:13:24.780665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12169,"took":"103.817781ms","hash":1565426763,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":25878528,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-14T18:13:24.780746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1565426763,"revision":12169,"compact-revision":9234} {"level":"info","ts":"2026-10-14T18:14:09.774929Z","caller":"traceutil/trace.go:171","msg":"trace[969582585] linearizableReadLoop","detail":"{readStateIndex:15637; appliedIndex:15636; }","duration":"311.33823ms","start":"2026-10-14T18:14:09.46356Z","end":"2026-10-14T18:14:09.774898Z","steps":["trace[969582585] 'read index received' (duration: 311.108046ms)","trace[969582585] 'applied index is now lower than readState.Index' (duration: 228.774µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:09.775063Z","caller":"traceutil/trace.go:171","msg":"trace[1916592933] transaction","detail":"{read_only:false; response_revision:14938; number_of_response:1; }","duration":"332.360802ms","start":"2026-10-14T18:14:09.442641Z","end":"2026-10-14T18:14:09.775002Z","steps":["trace[1916592933] 'process raft request' (duration: 332.044605ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:09.775243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.660179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-10-14T18:14:09.775334Z","caller":"traceutil/trace.go:171","msg":"trace[409813959] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:14938; }","duration":"311.799762ms","start":"2026-10-14T18:14:09.463511Z","end":"2026-10-14T18:14:09.775311Z","steps":["trace[409813959] 'agreement among raft nodes before linearized reading' (duration: 311.574567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:09.775378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:09.46349Z","time spent":"311.879203ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T18:14:09.775498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.002324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-14T18:14:09.775594Z","caller":"traceutil/trace.go:171","msg":"trace[599900954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14938; }","duration":"139.142967ms","start":"2026-10-14T18:14:09.636426Z","end":"2026-10-14T18:14:09.775569Z","steps":["trace[599900954] 'agreement among raft nodes before linearized reading' (duration: 138.866392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:09.775539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:09.442612Z","time spent":"332.684439ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:14:09.775644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.578666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"warn","ts":"2026-10-14T18:14:09.775693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.945257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-14T18:14:09.775741Z","caller":"traceutil/trace.go:171","msg":"trace[1715518930] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14938; }","duration":"117.698628ms","start":"2026-10-14T18:14:09.658017Z","end":"2026-10-14T18:14:09.775716Z","steps":["trace[1715518930] 'agreement among raft nodes before linearized reading' (duration: 117.402012ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:09.775759Z","caller":"traceutil/trace.go:171","msg":"trace[1661673985] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:14938; }","duration":"249.122241ms","start":"2026-10-14T18:14:09.526618Z","end":"2026-10-14T18:14:09.77574Z","steps":["trace[1661673985] 'agreement among raft nodes before linearized reading' (duration: 248.912697ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:09.775805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.909702ms","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:7"} {"level":"info","ts":"2026-10-14T18:14:09.775855Z","caller":"traceutil/trace.go:171","msg":"trace[885487612] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:14938; }","duration":"189.004974ms","start":"2026-10-14T18:14:09.586837Z","end":"2026-10-14T18:14:09.775842Z","steps":["trace[885487612] 'agreement among raft nodes before linearized reading' (duration: 188.881061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:09.776308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.026413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-10-14T18:14:09.776394Z","caller":"traceutil/trace.go:171","msg":"trace[287686707] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14938; }","duration":"303.149386ms","start":"2026-10-14T18:14:09.473222Z","end":"2026-10-14T18:14:09.776371Z","steps":["trace[287686707] 'agreement among raft nodes before linearized reading' (duration: 302.040253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:09.776411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.874818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-cmr5p\" ","response":"range_response_count:1 size:12591"} {"level":"warn","ts":"2026-10-14T18:14:09.776438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:09.473209Z","time spent":"303.219978ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T18:14:09.776458Z","caller":"traceutil/trace.go:171","msg":"trace[1978435140] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-cmr5p; range_end:; response_count:1; response_revision:14938; }","duration":"243.986121ms","start":"2026-10-14T18:14:09.532458Z","end":"2026-10-14T18:14:09.776444Z","steps":["trace[1978435140] 'agreement among raft nodes before linearized reading' (duration: 242.903207ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.198303Z","caller":"traceutil/trace.go:171","msg":"trace[1556511310] transaction","detail":"{read_only:false; response_revision:14940; number_of_response:1; }","duration":"417.129265ms","start":"2026-10-14T18:14:09.781147Z","end":"2026-10-14T18:14:10.198276Z","steps":["trace[1556511310] 'process raft request' (duration: 344.510061ms)","trace[1556511310] 'compare' (duration: 72.41816ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:14:10.198523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:09.781093Z","time spent":"417.345269ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:14:10.199159Z","caller":"traceutil/trace.go:171","msg":"trace[1865493976] transaction","detail":"{read_only:false; response_revision:14943; number_of_response:1; }","duration":"150.520511ms","start":"2026-10-14T18:14:10.048614Z","end":"2026-10-14T18:14:10.199135Z","steps":["trace[1865493976] 'process raft request' (duration: 150.469629ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.199573Z","caller":"traceutil/trace.go:171","msg":"trace[451935631] transaction","detail":"{read_only:false; response_revision:14941; number_of_response:1; }","duration":"415.322407ms","start":"2026-10-14T18:14:09.784237Z","end":"2026-10-14T18:14:10.19956Z","steps":["trace[451935631] 'process raft request' (duration: 413.983758ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.19972Z","caller":"traceutil/trace.go:171","msg":"trace[1420929103] linearizableReadLoop","detail":"{readStateIndex:15639; appliedIndex:15637; }","duration":"418.439873ms","start":"2026-10-14T18:14:09.781224Z","end":"2026-10-14T18:14:10.199664Z","steps":["trace[1420929103] 'read index received' (duration: 19.340884ms)","trace[1420929103] 'applied index is now lower than readState.Index' (duration: 399.094119ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:14:10.199771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:09.784217Z","time spent":"415.390548ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:14:10.199764Z","caller":"traceutil/trace.go:171","msg":"trace[1196725790] transaction","detail":"{read_only:false; response_revision:14942; number_of_response:1; }","duration":"406.871137ms","start":"2026-10-14T18:14:09.792878Z","end":"2026-10-14T18:14:10.19975Z","steps":["trace[1196725790] 'process raft request' (duration: 406.055339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:10.200018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.19208ms","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-10-14T18:14:10.200087Z","caller":"traceutil/trace.go:171","msg":"trace[1053615880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14943; }","duration":"283.408924ms","start":"2026-10-14T18:14:09.916666Z","end":"2026-10-14T18:14:10.200075Z","steps":["trace[1053615880] 'agreement among raft nodes before linearized reading' (duration: 283.186319ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:10.200129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.316372ms","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-10-14T18:14:10.199989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.7566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-10-14T18:14:10.200177Z","caller":"traceutil/trace.go:171","msg":"trace[829204808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14943; }","duration":"276.423215ms","start":"2026-10-14T18:14:09.92374Z","end":"2026-10-14T18:14:10.200163Z","steps":["trace[829204808] 'agreement among raft nodes before linearized reading' (duration: 276.297552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:10.2002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.818194ms","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-10-14T18:14:10.200278Z","caller":"traceutil/trace.go:171","msg":"trace[123359889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14943; }","duration":"227.962028ms","start":"2026-10-14T18:14:09.972297Z","end":"2026-10-14T18:14:10.200259Z","steps":["trace[123359889] 'agreement among raft nodes before linearized reading' (duration: 227.705403ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.200257Z","caller":"traceutil/trace.go:171","msg":"trace[1918533163] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:14943; }","duration":"419.050356ms","start":"2026-10-14T18:14:09.78116Z","end":"2026-10-14T18:14:10.200211Z","steps":["trace[1918533163] 'agreement among raft nodes before linearized reading' (duration: 418.664007ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:10.200285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:09.792864Z","time spent":"407.235523ms","remote":"127.0.0.1:55554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12705,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:14:10.200354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:14:09.781145Z","time spent":"419.180338ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":592,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T18:14:10.200668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.359233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:82 size:714698"} {"level":"info","ts":"2026-10-14T18:14:10.200734Z","caller":"traceutil/trace.go:171","msg":"trace[572932471] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:82; response_revision:14943; }","duration":"132.475754ms","start":"2026-10-14T18:14:10.068242Z","end":"2026-10-14T18:14:10.200718Z","steps":["trace[572932471] 'agreement among raft nodes before linearized reading' (duration: 131.685298ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.324691Z","caller":"traceutil/trace.go:171","msg":"trace[1078363405] transaction","detail":"{read_only:false; response_revision:14944; number_of_response:1; }","duration":"118.328742ms","start":"2026-10-14T18:14:10.206336Z","end":"2026-10-14T18:14:10.324665Z","steps":["trace[1078363405] 'process raft request' (duration: 118.175389ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.326785Z","caller":"traceutil/trace.go:171","msg":"trace[1404680892] transaction","detail":"{read_only:false; response_revision:14945; number_of_response:1; }","duration":"120.271022ms","start":"2026-10-14T18:14:10.206484Z","end":"2026-10-14T18:14:10.326755Z","steps":["trace[1404680892] 'process raft request' (duration: 120.067108ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.3271Z","caller":"traceutil/trace.go:171","msg":"trace[1786945084] transaction","detail":"{read_only:false; response_revision:14946; number_of_response:1; }","duration":"120.468618ms","start":"2026-10-14T18:14:10.206614Z","end":"2026-10-14T18:14:10.327083Z","steps":["trace[1786945084] 'process raft request' (duration: 120.08778ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.327411Z","caller":"traceutil/trace.go:171","msg":"trace[1157337583] linearizableReadLoop","detail":"{readStateIndex:15647; appliedIndex:15642; }","duration":"116.559625ms","start":"2026-10-14T18:14:10.210828Z","end":"2026-10-14T18:14:10.327387Z","steps":["trace[1157337583] 'read index received' (duration: 113.713284ms)","trace[1157337583] 'applied index is now lower than readState.Index' (duration: 2.845491ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:14:10.327452Z","caller":"traceutil/trace.go:171","msg":"trace[1728560166] transaction","detail":"{read_only:false; response_revision:14947; number_of_response:1; }","duration":"120.737273ms","start":"2026-10-14T18:14:10.206697Z","end":"2026-10-14T18:14:10.327434Z","steps":["trace[1728560166] 'process raft request' (duration: 120.304384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:14:10.327621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.79192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-cmr5p\" ","response":"range_response_count:1 size:12720"} {"level":"info","ts":"2026-10-14T18:14:10.327636Z","caller":"traceutil/trace.go:171","msg":"trace[1016389926] transaction","detail":"{read_only:false; response_revision:14948; number_of_response:1; }","duration":"120.115051ms","start":"2026-10-14T18:14:10.207502Z","end":"2026-10-14T18:14:10.327617Z","steps":["trace[1016389926] 'process raft request' (duration: 119.791953ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:14:10.327655Z","caller":"traceutil/trace.go:171","msg":"trace[565433002] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-cmr5p; range_end:; response_count:1; response_revision:14948; }","duration":"116.91931ms","start":"2026-10-14T18:14:10.210727Z","end":"2026-10-14T18:14:10.327646Z","steps":["trace[565433002] 'agreement among raft nodes before linearized reading' (duration: 116.754827ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:18:24.682953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14614} {"level":"info","ts":"2026-10-14T18:18:24.785921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14614,"took":"100.072573ms","hash":2541459098,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":29941760,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-14T18:18:24.785989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2541459098,"revision":14614,"compact-revision":12169} {"level":"info","ts":"2026-10-14T18:18:39.608666Z","caller":"traceutil/trace.go:171","msg":"trace[380579600] transaction","detail":"{read_only:false; response_revision:17837; number_of_response:1; }","duration":"117.74079ms","start":"2026-10-14T18:18:39.490896Z","end":"2026-10-14T18:18:39.608637Z","steps":["trace[380579600] 'process raft request' (duration: 93.645894ms)","trace[380579600] 'compare' (duration: 23.958754ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:21:07.324032Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:21:07.331063Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-14T18:21:07.331297Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-14T18:21:36.433759Z","caller":"traceutil/trace.go:171","msg":"trace[791411113] linearizableReadLoop","detail":"{readStateIndex:20257; appliedIndex:20256; }","duration":"178.175614ms","start":"2026-10-14T18:21:36.255565Z","end":"2026-10-14T18:21:36.43374Z","steps":["trace[791411113] 'read index received' (duration: 177.9656ms)","trace[791411113] 'applied index is now lower than readState.Index' (duration: 209.074µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:21:36.434115Z","caller":"traceutil/trace.go:171","msg":"trace[235069014] transaction","detail":"{read_only:false; response_revision:19349; number_of_response:1; }","duration":"178.884709ms","start":"2026-10-14T18:21:36.255216Z","end":"2026-10-14T18:21:36.434101Z","steps":["trace[235069014] 'process raft request' (duration: 178.385939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:21:36.434558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.977811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/senlin-db-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:21:36.43463Z","caller":"traceutil/trace.go:171","msg":"trace[494463328] range","detail":"{range_begin:/registry/serviceaccounts/openstack/senlin-db-init; range_end:; response_count:0; response_revision:19349; }","duration":"179.060324ms","start":"2026-10-14T18:21:36.255559Z","end":"2026-10-14T18:21:36.434619Z","steps":["trace[494463328] 'agreement among raft nodes before linearized reading' (duration: 178.760317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:21:36.775513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.469385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fgtnd\" ","response":"range_response_count:1 size:20724"} {"level":"info","ts":"2026-10-14T18:21:36.77561Z","caller":"traceutil/trace.go:171","msg":"trace[1986534076] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fgtnd; range_end:; response_count:1; response_revision:19349; }","duration":"160.602158ms","start":"2026-10-14T18:21:36.614984Z","end":"2026-10-14T18:21:36.775586Z","steps":["trace[1986534076] 'range keys from in-memory index tree' (duration: 160.289591ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:21:36.775651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.589271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-10-14T18:21:36.775739Z","caller":"traceutil/trace.go:171","msg":"trace[1094287792] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:19349; }","duration":"191.721675ms","start":"2026-10-14T18:21:36.583999Z","end":"2026-10-14T18:21:36.77572Z","steps":["trace[1094287792] 'range keys from in-memory index tree' (duration: 191.362707ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:21:36.775677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.035549ms","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-10-14T18:21:36.775823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.619044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/senlin-senlin-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:21:36.775847Z","caller":"traceutil/trace.go:171","msg":"trace[871420104] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19349; }","duration":"197.352855ms","start":"2026-10-14T18:21:36.578472Z","end":"2026-10-14T18:21:36.775825Z","steps":["trace[871420104] 'range keys from in-memory index tree' (duration: 196.846294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:21:36.775898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.758425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-14T18:21:36.775975Z","caller":"traceutil/trace.go:171","msg":"trace[1195221168] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:19349; }","duration":"105.887877ms","start":"2026-10-14T18:21:36.670069Z","end":"2026-10-14T18:21:36.775957Z","steps":["trace[1195221168] 'count revisions from in-memory index tree' (duration: 105.646472ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:21:36.775896Z","caller":"traceutil/trace.go:171","msg":"trace[1336173557] range","detail":"{range_begin:/registry/rolebindings/openstack/senlin-senlin-ks-endpoints; range_end:; response_count:0; response_revision:19349; }","duration":"257.711527ms","start":"2026-10-14T18:21:36.518162Z","end":"2026-10-14T18:21:36.775873Z","steps":["trace[1336173557] 'range keys from in-memory index tree' (duration: 257.523535ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:23:24.692894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17614} {"level":"info","ts":"2026-10-14T18:23:24.810952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17614,"took":"112.293755ms","hash":494437723,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":34869248,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:23:24.81103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":494437723,"revision":17614,"compact-revision":14614} {"level":"warn","ts":"2026-10-14T18:28:21.768953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.127968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:28:21.768985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.021608ms","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-10-14T18:28:21.769066Z","caller":"traceutil/trace.go:171","msg":"trace[1313817667] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:23293; }","duration":"200.301302ms","start":"2026-10-14T18:28:21.568746Z","end":"2026-10-14T18:28:21.769047Z","steps":["trace[1313817667] 'count revisions from in-memory index tree' (duration: 199.999365ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:21.769092Z","caller":"traceutil/trace.go:171","msg":"trace[888102170] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23293; }","duration":"118.185572ms","start":"2026-10-14T18:28:21.650884Z","end":"2026-10-14T18:28:21.769069Z","steps":["trace[888102170] 'range keys from in-memory index tree' (duration: 117.823975ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:22.582882Z","caller":"traceutil/trace.go:171","msg":"trace[2041762179] linearizableReadLoop","detail":"{readStateIndex:24385; appliedIndex:24384; }","duration":"226.159606ms","start":"2026-10-14T18:28:22.356703Z","end":"2026-10-14T18:28:22.582863Z","steps":["trace[2041762179] 'read index received' (duration: 225.91045ms)","trace[2041762179] 'applied index is now lower than readState.Index' (duration: 248.406µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:28:22.583008Z","caller":"traceutil/trace.go:171","msg":"trace[1573679372] transaction","detail":"{read_only:false; response_revision:23294; number_of_response:1; }","duration":"255.539265ms","start":"2026-10-14T18:28:22.327436Z","end":"2026-10-14T18:28:22.582975Z","steps":["trace[1573679372] 'process raft request' (duration: 255.25899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:22.583099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.392201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-14T18:28:22.583135Z","caller":"traceutil/trace.go:171","msg":"trace[1370528332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23294; }","duration":"226.494054ms","start":"2026-10-14T18:28:22.35663Z","end":"2026-10-14T18:28:22.583124Z","steps":["trace[1370528332] 'agreement among raft nodes before linearized reading' (duration: 226.34342ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:22.583267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.293007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:28:22.5834Z","caller":"traceutil/trace.go:171","msg":"trace[1958230907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23294; }","duration":"155.464331ms","start":"2026-10-14T18:28:22.42791Z","end":"2026-10-14T18:28:22.583374Z","steps":["trace[1958230907] 'agreement among raft nodes before linearized reading' (duration: 155.242276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:22.891233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.516019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:28:22.891379Z","caller":"traceutil/trace.go:171","msg":"trace[1472350549] linearizableReadLoop","detail":"{readStateIndex:24386; appliedIndex:24385; }","duration":"302.892219ms","start":"2026-10-14T18:28:22.588457Z","end":"2026-10-14T18:28:22.891349Z","steps":["trace[1472350549] 'read index received' (duration: 142.172946ms)","trace[1472350549] 'applied index is now lower than readState.Index' (duration: 160.717593ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:28:22.89155Z","caller":"traceutil/trace.go:171","msg":"trace[660056301] transaction","detail":"{read_only:false; response_revision:23295; number_of_response:1; }","duration":"304.28133ms","start":"2026-10-14T18:28:22.587236Z","end":"2026-10-14T18:28:22.891517Z","steps":["trace[660056301] 'process raft request' (duration: 143.372353ms)","trace[660056301] 'compare' (duration: 160.10125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:28:22.891702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.587214Z","time spent":"304.410012ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:28:22.89191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.422121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-14T18:28:22.892002Z","caller":"traceutil/trace.go:171","msg":"trace[223774855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23295; }","duration":"303.544114ms","start":"2026-10-14T18:28:22.588435Z","end":"2026-10-14T18:28:22.891979Z","steps":["trace[223774855] 'agreement among raft nodes before linearized reading' (duration: 303.284648ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:22.892064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.588426Z","time spent":"303.628235ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-14T18:28:22.901238Z","caller":"traceutil/trace.go:171","msg":"trace[101016032] transaction","detail":"{read_only:false; number_of_response:0; response_revision:23295; }","duration":"311.478983ms","start":"2026-10-14T18:28:22.589699Z","end":"2026-10-14T18:28:22.901178Z","steps":["trace[101016032] 'process raft request' (duration: 311.243638ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:22.901291Z","caller":"traceutil/trace.go:171","msg":"trace[752964398] transaction","detail":"{read_only:false; response_revision:23296; number_of_response:1; }","duration":"310.262087ms","start":"2026-10-14T18:28:22.590997Z","end":"2026-10-14T18:28:22.901259Z","steps":["trace[752964398] 'process raft request' (duration: 310.056653ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:22.90133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.928411ms","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-10-14T18:28:22.901632Z","caller":"traceutil/trace.go:171","msg":"trace[409498583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23296; }","duration":"213.272419ms","start":"2026-10-14T18:28:22.688336Z","end":"2026-10-14T18:28:22.901608Z","steps":["trace[409498583] 'agreement among raft nodes before linearized reading' (duration: 212.801449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:22.901417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.589683Z","time spent":"311.672938ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":30,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-14T18:28:22.901448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.590987Z","time spent":"310.37561ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:28:23.272479Z","caller":"traceutil/trace.go:171","msg":"trace[474033572] linearizableReadLoop","detail":"{readStateIndex:24391; appliedIndex:24390; }","duration":"282.114764ms","start":"2026-10-14T18:28:22.990344Z","end":"2026-10-14T18:28:23.272459Z","steps":["trace[474033572] 'read index received' (duration: 282.000221ms)","trace[474033572] 'applied index is now lower than readState.Index' (duration: 113.303µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:28:23.272546Z","caller":"traceutil/trace.go:171","msg":"trace[1417364994] transaction","detail":"{read_only:false; response_revision:23299; number_of_response:1; }","duration":"313.904715ms","start":"2026-10-14T18:28:22.958611Z","end":"2026-10-14T18:28:23.272516Z","steps":["trace[1417364994] 'process raft request' (duration: 313.698511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:23.272717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.216144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-14T18:28:23.2728Z","caller":"traceutil/trace.go:171","msg":"trace[532538334] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23299; }","duration":"322.311375ms","start":"2026-10-14T18:28:22.950451Z","end":"2026-10-14T18:28:23.272762Z","steps":["trace[532538334] 'agreement among raft nodes before linearized reading' (duration: 322.115861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:23.272873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.950439Z","time spent":"322.404257ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-14T18:28:23.273127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.958587Z","time spent":"314.179821ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:28:23.476596Z","caller":"traceutil/trace.go:171","msg":"trace[1212258690] transaction","detail":"{read_only:false; response_revision:23300; number_of_response:1; }","duration":"482.361686ms","start":"2026-10-14T18:28:22.994177Z","end":"2026-10-14T18:28:23.476539Z","steps":["trace[1212258690] 'process raft request' (duration: 396.938265ms)","trace[1212258690] 'compare' (duration: 84.955591ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:28:23.476762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.994163Z","time spent":"482.515078ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:28:23.476751Z","caller":"traceutil/trace.go:171","msg":"trace[1945442172] transaction","detail":"{read_only:false; response_revision:23301; number_of_response:1; }","duration":"482.470947ms","start":"2026-10-14T18:28:22.994245Z","end":"2026-10-14T18:28:23.476716Z","steps":["trace[1945442172] 'process raft request' (duration: 482.228432ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:23.476796Z","caller":"traceutil/trace.go:171","msg":"trace[1188021003] transaction","detail":"{read_only:false; response_revision:23302; number_of_response:1; }","duration":"193.031356ms","start":"2026-10-14T18:28:23.283746Z","end":"2026-10-14T18:28:23.476777Z","steps":["trace[1188021003] 'process raft request' (duration: 192.957514ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:23.476801Z","caller":"traceutil/trace.go:171","msg":"trace[1347876961] linearizableReadLoop","detail":"{readStateIndex:24393; appliedIndex:24391; }","duration":"204.143274ms","start":"2026-10-14T18:28:23.272634Z","end":"2026-10-14T18:28:23.476777Z","steps":["trace[1347876961] 'read index received' (duration: 118.794265ms)","trace[1347876961] 'applied index is now lower than readState.Index' (duration: 85.347719ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:28:23.476924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:22.994222Z","time spent":"482.61345ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:28:23.47706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.320431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-10-14T18:28:23.477101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.715665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"warn","ts":"2026-10-14T18:28:23.477131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.543248ms","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-10-14T18:28:23.477166Z","caller":"traceutil/trace.go:171","msg":"trace[86609640] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23302; }","duration":"476.820246ms","start":"2026-10-14T18:28:23.00033Z","end":"2026-10-14T18:28:23.47715Z","steps":["trace[86609640] 'agreement among raft nodes before linearized reading' (duration: 476.618292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:23.477209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.794387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:28:23.477228Z","caller":"traceutil/trace.go:171","msg":"trace[871646555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23302; }","duration":"194.668451ms","start":"2026-10-14T18:28:23.282542Z","end":"2026-10-14T18:28:23.47721Z","steps":["trace[871646555] 'agreement among raft nodes before linearized reading' (duration: 194.437636ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:23.477244Z","caller":"traceutil/trace.go:171","msg":"trace[1031035468] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:23302; }","duration":"456.882468ms","start":"2026-10-14T18:28:23.020353Z","end":"2026-10-14T18:28:23.477236Z","steps":["trace[1031035468] 'agreement among raft nodes before linearized reading' (duration: 456.803806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:23.477251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:23.000311Z","time spent":"476.926308ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":551,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-14T18:28:23.477281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:23.020339Z","time spent":"456.93191ms","remote":"127.0.0.1:35744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:28:23.47712Z","caller":"traceutil/trace.go:171","msg":"trace[1273775078] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23302; }","duration":"311.427224ms","start":"2026-10-14T18:28:23.165668Z","end":"2026-10-14T18:28:23.477095Z","steps":["trace[1273775078] 'agreement among raft nodes before linearized reading' (duration: 311.236679ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:23.4774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:28:23.165644Z","time spent":"311.742289ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":381,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-10-14T18:28:23.477443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.797384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T18:28:23.477478Z","caller":"traceutil/trace.go:171","msg":"trace[1678576337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23302; }","duration":"194.868495ms","start":"2026-10-14T18:28:23.282601Z","end":"2026-10-14T18:28:23.47747Z","steps":["trace[1678576337] 'agreement among raft nodes before linearized reading' (duration: 194.775773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:28:23.477232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.032904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11493"} {"level":"info","ts":"2026-10-14T18:28:23.477772Z","caller":"traceutil/trace.go:171","msg":"trace[1913817529] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23302; }","duration":"171.611197ms","start":"2026-10-14T18:28:23.306148Z","end":"2026-10-14T18:28:23.477759Z","steps":["trace[1913817529] 'agreement among raft nodes before linearized reading' (duration: 170.931462ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:23.607624Z","caller":"traceutil/trace.go:171","msg":"trace[1659485014] transaction","detail":"{read_only:false; response_revision:23303; number_of_response:1; }","duration":"124.707201ms","start":"2026-10-14T18:28:23.482882Z","end":"2026-10-14T18:28:23.60759Z","steps":["trace[1659485014] 'process raft request' (duration: 105.087291ms)","trace[1659485014] 'compare' (duration: 19.486168ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:28:23.608809Z","caller":"traceutil/trace.go:171","msg":"trace[346386869] transaction","detail":"{read_only:false; response_revision:23304; number_of_response:1; }","duration":"125.015148ms","start":"2026-10-14T18:28:23.483765Z","end":"2026-10-14T18:28:23.60878Z","steps":["trace[346386869] 'process raft request' (duration: 124.833194ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:23.609369Z","caller":"traceutil/trace.go:171","msg":"trace[1828601161] transaction","detail":"{read_only:false; response_revision:23305; number_of_response:1; }","duration":"125.257653ms","start":"2026-10-14T18:28:23.484088Z","end":"2026-10-14T18:28:23.609345Z","steps":["trace[1828601161] 'process raft request' (duration: 124.637179ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:23.609746Z","caller":"traceutil/trace.go:171","msg":"trace[1446609997] transaction","detail":"{read_only:false; response_revision:23306; number_of_response:1; }","duration":"113.89299ms","start":"2026-10-14T18:28:23.49584Z","end":"2026-10-14T18:28:23.609733Z","steps":["trace[1446609997] 'process raft request' (duration: 113.271497ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:23.612318Z","caller":"traceutil/trace.go:171","msg":"trace[1981873650] linearizableReadLoop","detail":"{readStateIndex:24397; appliedIndex:24394; }","duration":"120.086102ms","start":"2026-10-14T18:28:23.492212Z","end":"2026-10-14T18:28:23.612298Z","steps":["trace[1981873650] 'read index received' (duration: 95.755971ms)","trace[1981873650] 'applied index is now lower than readState.Index' (duration: 24.328591ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:28:23.612666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.439531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-10-14T18:28:23.612724Z","caller":"traceutil/trace.go:171","msg":"trace[698852939] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:23306; }","duration":"120.537152ms","start":"2026-10-14T18:28:23.492171Z","end":"2026-10-14T18:28:23.612709Z","steps":["trace[698852939] 'agreement among raft nodes before linearized reading' (duration: 120.201025ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:28:24.700114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20604} {"level":"info","ts":"2026-10-14T18:28:24.83211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20604,"took":"128.796811ms","hash":104917017,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":32497664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-14T18:28:24.832175Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":104917017,"revision":20604,"compact-revision":17614} {"level":"info","ts":"2026-10-14T18:32:00.252003Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069d9.wal"} {"level":"warn","ts":"2026-10-14T18:32:06.555421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.355161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-14T18:32:06.555993Z","caller":"traceutil/trace.go:171","msg":"trace[446538045] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"295.708246ms","start":"2026-10-14T18:32:06.260251Z","end":"2026-10-14T18:32:06.555959Z","steps":["trace[446538045] 'process raft request' (duration: 102.733491ms)","trace[446538045] 'compare' (duration: 192.239569ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:06.556082Z","caller":"traceutil/trace.go:171","msg":"trace[1609195189] linearizableReadLoop","detail":"{readStateIndex:27219; appliedIndex:27217; }","duration":"267.891979ms","start":"2026-10-14T18:32:06.288173Z","end":"2026-10-14T18:32:06.556065Z","steps":["trace[1609195189] 'read index received' (duration: 74.866494ms)","trace[1609195189] 'applied index is now lower than readState.Index' (duration: 193.024305ms)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:32:06.556092Z","caller":"traceutil/trace.go:171","msg":"trace[608906439] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"295.271376ms","start":"2026-10-14T18:32:06.260786Z","end":"2026-10-14T18:32:06.556057Z","steps":["trace[608906439] 'process raft request' (duration: 294.741845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:06.556296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.032371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:32:06.55631Z","caller":"traceutil/trace.go:171","msg":"trace[387213406] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"108.193068ms","start":"2026-10-14T18:32:06.448103Z","end":"2026-10-14T18:32:06.556296Z","steps":["trace[387213406] 'process raft request' (duration: 108.088227ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:06.556281Z","caller":"traceutil/trace.go:171","msg":"trace[831297660] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"156.167067ms","start":"2026-10-14T18:32:06.400072Z","end":"2026-10-14T18:32:06.55624Z","steps":["trace[831297660] 'process raft request' (duration: 155.929202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:32:06.556306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.109685ms","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:6"} {"level":"info","ts":"2026-10-14T18:32:06.556329Z","caller":"traceutil/trace.go:171","msg":"trace[2002393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26015; }","duration":"127.145014ms","start":"2026-10-14T18:32:06.429175Z","end":"2026-10-14T18:32:06.55632Z","steps":["trace[2002393] 'agreement among raft nodes before linearized reading' (duration: 127.061783ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:32:06.557418Z","caller":"traceutil/trace.go:171","msg":"trace[406978414] 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:26014; }","duration":"268.218216ms","start":"2026-10-14T18:32:06.288138Z","end":"2026-10-14T18:32:06.556356Z","steps":["trace[406978414] 'agreement among raft nodes before linearized reading' (duration: 268.006181ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:33:24.713862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23307} {"level":"info","ts":"2026-10-14T18:33:24.833655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23307,"took":"116.798812ms","hash":1380524713,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":45580288,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-14T18:33:24.833745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1380524713,"revision":23307,"compact-revision":20604} {"level":"info","ts":"2026-10-14T18:35:19.892999Z","caller":"traceutil/trace.go:171","msg":"trace[2053751010] transaction","detail":"{read_only:false; response_revision:28280; number_of_response:1; }","duration":"135.715707ms","start":"2026-10-14T18:35:19.757256Z","end":"2026-10-14T18:35:19.892971Z","steps":["trace[2053751010] 'process raft request' (duration: 135.519282ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:25.427891Z","caller":"traceutil/trace.go:171","msg":"trace[2071372463] transaction","detail":"{read_only:false; response_revision:28324; number_of_response:1; }","duration":"139.827485ms","start":"2026-10-14T18:35:25.288046Z","end":"2026-10-14T18:35:25.427873Z","steps":["trace[2071372463] 'process raft request' (duration: 139.59087ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:25.625723Z","caller":"traceutil/trace.go:171","msg":"trace[1911202385] transaction","detail":"{read_only:false; response_revision:28325; number_of_response:1; }","duration":"336.237301ms","start":"2026-10-14T18:35:25.289367Z","end":"2026-10-14T18:35:25.625604Z","steps":["trace[1911202385] 'process raft request' (duration: 336.005196ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:25.625846Z","caller":"traceutil/trace.go:171","msg":"trace[1909096263] transaction","detail":"{read_only:false; response_revision:28326; number_of_response:1; }","duration":"328.990767ms","start":"2026-10-14T18:35:25.296836Z","end":"2026-10-14T18:35:25.625827Z","steps":["trace[1909096263] 'process raft request' (duration: 328.722831ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:25.625831Z","caller":"traceutil/trace.go:171","msg":"trace[1417983436] linearizableReadLoop","detail":"{readStateIndex:29620; appliedIndex:29617; }","duration":"322.473836ms","start":"2026-10-14T18:35:25.303329Z","end":"2026-10-14T18:35:25.625803Z","steps":["trace[1417983436] 'read index received' (duration: 124.380923ms)","trace[1417983436] 'applied index is now lower than readState.Index' (duration: 198.091143ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:25.625937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:25.296811Z","time spent":"329.072797ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-14T18:35:25.626005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.65639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:35:25.626059Z","caller":"traceutil/trace.go:171","msg":"trace[1421535174] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:28326; }","duration":"322.754852ms","start":"2026-10-14T18:35:25.303293Z","end":"2026-10-14T18:35:25.626048Z","steps":["trace[1421535174] 'agreement among raft nodes before linearized reading' (duration: 322.6479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:25.626068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.745155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-14T18:35:25.626095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:25.289354Z","time spent":"336.460816ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-14T18:35:25.626143Z","caller":"traceutil/trace.go:171","msg":"trace[569799546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28326; }","duration":"197.872128ms","start":"2026-10-14T18:35:25.428254Z","end":"2026-10-14T18:35:25.626127Z","steps":["trace[569799546] 'agreement among raft nodes before linearized reading' (duration: 197.729646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:35:25.62609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-14T18:35:25.30328Z","time spent":"322.799994ms","remote":"127.0.0.1:41216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-10-14T18:35:25.836668Z","caller":"traceutil/trace.go:171","msg":"trace[1170516182] linearizableReadLoop","detail":"{readStateIndex:29622; appliedIndex:29621; }","duration":"119.263714ms","start":"2026-10-14T18:35:25.717377Z","end":"2026-10-14T18:35:25.836641Z","steps":["trace[1170516182] 'read index received' (duration: 118.170201ms)","trace[1170516182] 'applied index is now lower than readState.Index' (duration: 1.092413ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:25.836862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.467549ms","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-10-14T18:35:25.836898Z","caller":"traceutil/trace.go:171","msg":"trace[395732236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28328; }","duration":"119.557491ms","start":"2026-10-14T18:35:25.717331Z","end":"2026-10-14T18:35:25.836889Z","steps":["trace[395732236] 'agreement among raft nodes before linearized reading' (duration: 119.403958ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:25.837041Z","caller":"traceutil/trace.go:171","msg":"trace[1332041510] transaction","detail":"{read_only:false; response_revision:28328; number_of_response:1; }","duration":"167.277703ms","start":"2026-10-14T18:35:25.669691Z","end":"2026-10-14T18:35:25.836969Z","steps":["trace[1332041510] 'process raft request' (duration: 165.930524ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:26.022776Z","caller":"traceutil/trace.go:171","msg":"trace[1123311696] linearizableReadLoop","detail":"{readStateIndex:29624; appliedIndex:29622; }","duration":"102.000005ms","start":"2026-10-14T18:35:25.920752Z","end":"2026-10-14T18:35:26.022752Z","steps":["trace[1123311696] 'read index received' (duration: 19.12782ms)","trace[1123311696] 'applied index is now lower than readState.Index' (duration: 82.871075ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:26.023081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.30399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-14T18:35:26.023124Z","caller":"traceutil/trace.go:171","msg":"trace[533874283] transaction","detail":"{read_only:false; response_revision:28329; number_of_response:1; }","duration":"181.415315ms","start":"2026-10-14T18:35:25.841694Z","end":"2026-10-14T18:35:26.02311Z","steps":["trace[533874283] 'process raft request' (duration: 98.171792ms)","trace[533874283] 'compare' (duration: 82.719151ms)"],"step_count":2} {"level":"warn","ts":"2026-10-14T18:35:26.02311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.43301ms","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-10-14T18:35:26.023165Z","caller":"traceutil/trace.go:171","msg":"trace[1489504219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28330; }","duration":"102.431764ms","start":"2026-10-14T18:35:25.920716Z","end":"2026-10-14T18:35:26.023148Z","steps":["trace[1489504219] 'agreement among raft nodes before linearized reading' (duration: 102.2253ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:26.023199Z","caller":"traceutil/trace.go:171","msg":"trace[1127984168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28330; }","duration":"100.551244ms","start":"2026-10-14T18:35:25.922625Z","end":"2026-10-14T18:35:26.023177Z","steps":["trace[1127984168] 'agreement among raft nodes before linearized reading' (duration: 100.36367ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:26.02316Z","caller":"traceutil/trace.go:171","msg":"trace[464550703] transaction","detail":"{read_only:false; response_revision:28330; number_of_response:1; }","duration":"110.493686ms","start":"2026-10-14T18:35:25.912635Z","end":"2026-10-14T18:35:26.023129Z","steps":["trace[464550703] 'process raft request' (duration: 110.074328ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:35:57.783191Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:35:57.791145Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-14T18:35:57.791238Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"warn","ts":"2026-10-14T18:36:24.737472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.787807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-14T18:36:24.737554Z","caller":"traceutil/trace.go:171","msg":"trace[2121760865] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:28945; }","duration":"141.889549ms","start":"2026-10-14T18:36:24.595647Z","end":"2026-10-14T18:36:24.737537Z","steps":["trace[2121760865] 'count revisions from in-memory index tree' (duration: 141.702765ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:25.655175Z","caller":"traceutil/trace.go:171","msg":"trace[1459591392] transaction","detail":"{read_only:false; response_revision:28946; number_of_response:1; }","duration":"215.315842ms","start":"2026-10-14T18:36:25.439832Z","end":"2026-10-14T18:36:25.655148Z","steps":["trace[1459591392] 'process raft request' (duration: 215.136787ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:46.266981Z","caller":"traceutil/trace.go:171","msg":"trace[778534547] transaction","detail":"{read_only:false; response_revision:29127; number_of_response:1; }","duration":"119.255704ms","start":"2026-10-14T18:36:46.147698Z","end":"2026-10-14T18:36:46.266954Z","steps":["trace[778534547] 'process raft request' (duration: 118.932618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-14T18:36:46.267383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.651785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:569"} {"level":"warn","ts":"2026-10-14T18:36:46.267354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.574074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-90a2e76a-0c43-42cb-9fe9-eb185219d109\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-10-14T18:36:46.267474Z","caller":"traceutil/trace.go:171","msg":"trace[700836675] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:29127; }","duration":"120.784667ms","start":"2026-10-14T18:36:46.146666Z","end":"2026-10-14T18:36:46.267451Z","steps":["trace[700836675] 'agreement among raft nodes before linearized reading' (duration: 120.584863ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:46.267687Z","caller":"traceutil/trace.go:171","msg":"trace[70834067] range","detail":"{range_begin:/registry/persistentvolumes/pvc-90a2e76a-0c43-42cb-9fe9-eb185219d109; range_end:; response_count:1; response_revision:29127; }","duration":"120.738846ms","start":"2026-10-14T18:36:46.14673Z","end":"2026-10-14T18:36:46.267469Z","steps":["trace[70834067] 'agreement among raft nodes before linearized reading' (duration: 120.241275ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:36:46.267997Z","caller":"traceutil/trace.go:171","msg":"trace[2143651098] linearizableReadLoop","detail":"{readStateIndex:30454; appliedIndex:30453; }","duration":"103.455436ms","start":"2026-10-14T18:36:46.164061Z","end":"2026-10-14T18:36:46.267516Z","steps":["trace[2143651098] 'read index received' (duration: 102.620047ms)","trace[2143651098] 'applied index is now lower than readState.Index' (duration: 241.956µs)"],"step_count":2} {"level":"info","ts":"2026-10-14T18:36:46.268711Z","caller":"traceutil/trace.go:171","msg":"trace[192568087] transaction","detail":"{read_only:false; response_revision:29128; number_of_response:1; }","duration":"100.281648ms","start":"2026-10-14T18:36:46.167886Z","end":"2026-10-14T18:36:46.268167Z","steps":["trace[192568087] 'process raft request' (duration: 99.815258ms)"],"step_count":1} {"level":"info","ts":"2026-10-14T18:38:24.726343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26963} {"level":"info","ts":"2026-10-14T18:38:24.90994Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26963,"took":"175.477108ms","hash":2580857067,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":36036608,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-14T18:38:24.910068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2580857067,"revision":26963,"compact-revision":23307} {"level":"info","ts":"2026-10-14T18:43:24.734653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30254} {"level":"info","ts":"2026-10-14T18:43:24.878952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30254,"took":"139.953504ms","hash":550566470,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":36753408,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-14T18:43:24.878994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":550566470,"revision":30254,"compact-revision":26963} {"level":"info","ts":"2026-10-14T18:48:24.741697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33294} {"level":"info","ts":"2026-10-14T18:48:24.894562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33294,"took":"148.6314ms","hash":2301175054,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":34390016,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T18:48:24.894624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2301175054,"revision":33294,"compact-revision":30254} {"level":"info","ts":"2026-10-14T18:52:37.865755Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b512f7d8ac93be25","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-14T18:52:37.87235Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-14T18:52:37.872455Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-14T18:53:24.748493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35966} {"level":"info","ts":"2026-10-14T18:53:24.885324Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35966,"took":"132.655384ms","hash":1562542414,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T18:53:24.885391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1562542414,"revision":35966,"compact-revision":33294} {"level":"info","ts":"2026-10-14T18:58:24.756011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38683} {"level":"info","ts":"2026-10-14T18:58:24.904484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38683,"took":"143.998496ms","hash":3438391783,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":34848768,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-14T18:58:24.904551Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3438391783,"revision":38683,"compact-revision":35966} {"level":"info","ts":"2026-10-14T19:03:24.762526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41423} {"level":"info","ts":"2026-10-14T19:03:24.918868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41423,"took":"151.789283ms","hash":3765714873,"current-db-size-bytes":61353984,"current-db-size":"61 MB","current-db-size-in-use-bytes":34177024,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-14T19:03:24.918969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3765714873,"revision":41423,"compact-revision":38683}