{"level":"warn","ts":"2026-05-05T02:27:42.844062Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T02:27:42.844167Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.117:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.117:2380","--initial-cluster=instance=https://199.19.213.117:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.117:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.117:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T02:27:42.844236Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T02:27:42.844246Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-05-05T02:27:42.84427Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T02:27:42.845115Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"]} {"level":"info","ts":"2026-05-05T02:27:42.845202Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.117:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T02:27:42.849162Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.729202ms"} {"level":"info","ts":"2026-05-05T02:27:42.854882Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f9f905057b5b268","cluster-id":"293172f52aeb3f03"} {"level":"info","ts":"2026-05-05T02:27:42.854973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T02:27:42.854997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 0"} {"level":"info","ts":"2026-05-05T02:27:42.855013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f9f905057b5b268 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T02:27:42.855028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became follower at term 1"} {"level":"info","ts":"2026-05-05T02:27:42.855088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"warn","ts":"2026-05-05T02:27:42.865822Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T02:27:42.868849Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T02:27:42.870324Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T02:27:42.872985Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f9f905057b5b268","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T02:27:42.873259Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f9f905057b5b268","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T02:27:42.87339Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T02:27:42.873466Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T02:27:42.873475Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T02:27:42.88395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 switched to configuration voters=(6890384629643457128)"} {"level":"info","ts":"2026-05-05T02:27:42.884159Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","added-peer-id":"5f9f905057b5b268","added-peer-peer-urls":["https://199.19.213.117:2380"]} {"level":"info","ts":"2026-05-05T02:27:42.886584Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T02:27:42.886991Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f9f905057b5b268","initial-advertise-peer-urls":["https://199.19.213.117:2380"],"listen-peer-urls":["https://199.19.213.117:2380"],"advertise-client-urls":["https://199.19.213.117:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.117:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T02:27:42.887044Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T02:27:42.887153Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-05-05T02:27:42.88717Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.117:2380"} {"level":"info","ts":"2026-05-05T02:27:43.756169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T02:27:43.756291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T02:27:43.756337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgPreVoteResp from 5f9f905057b5b268 at term 1"} {"level":"info","ts":"2026-05-05T02:27:43.756366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became candidate at term 2"} {"level":"info","ts":"2026-05-05T02:27:43.756386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 received MsgVoteResp from 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-05-05T02:27:43.756412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f9f905057b5b268 became leader at term 2"} {"level":"info","ts":"2026-05-05T02:27:43.756438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f9f905057b5b268 elected leader 5f9f905057b5b268 at term 2"} {"level":"info","ts":"2026-05-05T02:27:43.75742Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:43.758238Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f9f905057b5b268","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.117:2379]}","request-path":"/0/members/5f9f905057b5b268/attributes","cluster-id":"293172f52aeb3f03","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T02:27:43.758432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:27:43.758447Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T02:27:43.758543Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T02:27:43.75867Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T02:27:43.758856Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"293172f52aeb3f03","local-member-id":"5f9f905057b5b268","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:43.759022Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:43.759085Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T02:27:43.761786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T02:27:43.762344Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.117:2379"} {"level":"info","ts":"2026-05-05T02:27:59.613734Z","caller":"traceutil/trace.go:171","msg":"trace[1290948979] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"137.92209ms","start":"2026-05-05T02:27:59.475776Z","end":"2026-05-05T02:27:59.613698Z","steps":["trace[1290948979] 'process raft request' (duration: 137.726625ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:27:59.759315Z","caller":"traceutil/trace.go:171","msg":"trace[1788804907] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"273.658926ms","start":"2026-05-05T02:27:59.485627Z","end":"2026-05-05T02:27:59.759286Z","steps":["trace[1788804907] 'process raft request' (duration: 273.326258ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:27:59.933348Z","caller":"traceutil/trace.go:171","msg":"trace[928360863] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"157.693488ms","start":"2026-05-05T02:27:59.775626Z","end":"2026-05-05T02:27:59.93332Z","steps":["trace[928360863] 'process raft request' (duration: 157.530274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:21.311493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.330582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T02:28:21.311676Z","caller":"traceutil/trace.go:171","msg":"trace[449342157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"437.574127ms","start":"2026-05-05T02:28:20.874071Z","end":"2026-05-05T02:28:21.311645Z","steps":["trace[449342157] 'range keys from in-memory index tree' (duration: 437.119486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:21.311734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:28:20.874047Z","time spent":"437.673489ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T02:28:21.313082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.223639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T02:28:21.313303Z","caller":"traceutil/trace.go:171","msg":"trace[20153332] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"424.497365ms","start":"2026-05-05T02:28:20.888734Z","end":"2026-05-05T02:28:21.313231Z","steps":["trace[20153332] 'process raft request' (duration: 117.314372ms)","trace[20153332] 'compare' (duration: 305.542832ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:28:21.313458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:28:20.888699Z","time spent":"424.664329ms","remote":"127.0.0.1:34662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:28:21.491935Z","caller":"traceutil/trace.go:171","msg":"trace[110570451] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"174.259205ms","start":"2026-05-05T02:28:21.31764Z","end":"2026-05-05T02:28:21.491899Z","steps":["trace[110570451] 'process raft request' (duration: 126.679112ms)","trace[110570451] 'compare' (duration: 47.42568ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:28:21.495755Z","caller":"traceutil/trace.go:171","msg":"trace[1178647200] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"120.761838ms","start":"2026-05-05T02:28:21.374965Z","end":"2026-05-05T02:28:21.495727Z","steps":["trace[1178647200] 'process raft request' (duration: 120.530342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:35.689366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.925493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:28:35.689467Z","caller":"traceutil/trace.go:171","msg":"trace[794252698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"245.070828ms","start":"2026-05-05T02:28:35.444377Z","end":"2026-05-05T02:28:35.689448Z","steps":["trace[794252698] 'range keys from in-memory index tree' (duration: 244.832251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:35.689363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.658779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T02:28:35.689539Z","caller":"traceutil/trace.go:171","msg":"trace[274304245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"132.858405ms","start":"2026-05-05T02:28:35.556651Z","end":"2026-05-05T02:28:35.68951Z","steps":["trace[274304245] 'range keys from in-memory index tree' (duration: 132.475485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:28:35.689461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.724448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T02:28:35.689678Z","caller":"traceutil/trace.go:171","msg":"trace[1051648711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"139.978694ms","start":"2026-05-05T02:28:35.549669Z","end":"2026-05-05T02:28:35.689647Z","steps":["trace[1051648711] 'range keys from in-memory index tree' (duration: 139.536742ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:51.974003Z","caller":"traceutil/trace.go:171","msg":"trace[549610023] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1872; }","duration":"150.96105ms","start":"2026-05-05T02:29:51.82301Z","end":"2026-05-05T02:29:51.973971Z","steps":["trace[549610023] 'read index received' (duration: 150.780824ms)","trace[549610023] 'applied index is now lower than readState.Index' (duration: 178.936µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:51.974168Z","caller":"traceutil/trace.go:171","msg":"trace[1152404150] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"198.921679ms","start":"2026-05-05T02:29:51.775193Z","end":"2026-05-05T02:29:51.974114Z","steps":["trace[1152404150] 'process raft request' (duration: 198.564789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:51.97428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.236566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:29:51.974386Z","caller":"traceutil/trace.go:171","msg":"trace[1625793690] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1807; }","duration":"151.439811ms","start":"2026-05-05T02:29:51.822924Z","end":"2026-05-05T02:29:51.974364Z","steps":["trace[1625793690] 'agreement among raft nodes before linearized reading' (duration: 151.223566ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:52.027134Z","caller":"traceutil/trace.go:171","msg":"trace[661773554] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"130.958017ms","start":"2026-05-05T02:29:51.896135Z","end":"2026-05-05T02:29:52.027093Z","steps":["trace[661773554] 'process raft request' (duration: 130.651518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:52.027173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.986335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a31d0f7b-4e2d-4d4f-aa0a-00dc410ec957\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-05T02:29:52.027244Z","caller":"traceutil/trace.go:171","msg":"trace[638804777] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a31d0f7b-4e2d-4d4f-aa0a-00dc410ec957; range_end:; response_count:1; response_revision:1808; }","duration":"127.105048ms","start":"2026-05-05T02:29:51.900119Z","end":"2026-05-05T02:29:52.027225Z","steps":["trace[638804777] 'agreement among raft nodes before linearized reading' (duration: 126.848261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:52.366958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.104675ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-05T02:29:52.400162Z","caller":"traceutil/trace.go:171","msg":"trace[1289905030] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1875; }","duration":"241.986083ms","start":"2026-05-05T02:29:52.158159Z","end":"2026-05-05T02:29:52.400145Z","steps":["trace[1289905030] 'read index received' (duration: 241.808329ms)","trace[1289905030] 'applied index is now lower than readState.Index' (duration: 176.334µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:29:52.400248Z","caller":"traceutil/trace.go:171","msg":"trace[254552050] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"243.151343ms","start":"2026-05-05T02:29:52.157089Z","end":"2026-05-05T02:29:52.40024Z","steps":["trace[254552050] 'process raft request' (duration: 242.925827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:52.400544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.365733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T02:29:52.400587Z","caller":"traceutil/trace.go:171","msg":"trace[1661815997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1809; }","duration":"242.472636ms","start":"2026-05-05T02:29:52.158104Z","end":"2026-05-05T02:29:52.400577Z","steps":["trace[1661815997] 'agreement among raft nodes before linearized reading' (duration: 242.313032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:52.400589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.654024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-05-05T02:29:52.400577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.039638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T02:29:52.400617Z","caller":"traceutil/trace.go:171","msg":"trace[1598060863] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1809; }","duration":"209.723636ms","start":"2026-05-05T02:29:52.190881Z","end":"2026-05-05T02:29:52.400605Z","steps":["trace[1598060863] 'agreement among raft nodes before linearized reading' (duration: 209.626513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:52.400653Z","caller":"traceutil/trace.go:171","msg":"trace[250238241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1809; }","duration":"241.179602ms","start":"2026-05-05T02:29:52.159457Z","end":"2026-05-05T02:29:52.400637Z","steps":["trace[250238241] 'agreement among raft nodes before linearized reading' (duration: 240.886174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:29:52.400669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.75512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a31d0f7b-4e2d-4d4f-aa0a-00dc410ec957\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-05-05T02:29:52.400712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.181408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T02:29:52.400793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.826822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T02:29:52.400817Z","caller":"traceutil/trace.go:171","msg":"trace[1437893689] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1809; }","duration":"128.851353ms","start":"2026-05-05T02:29:52.271959Z","end":"2026-05-05T02:29:52.400811Z","steps":["trace[1437893689] 'agreement among raft nodes before linearized reading' (duration: 128.784591ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:52.400784Z","caller":"traceutil/trace.go:171","msg":"trace[313302040] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a31d0f7b-4e2d-4d4f-aa0a-00dc410ec957; range_end:; response_count:1; response_revision:1809; }","duration":"199.949695ms","start":"2026-05-05T02:29:52.200815Z","end":"2026-05-05T02:29:52.400765Z","steps":["trace[313302040] 'agreement among raft nodes before linearized reading' (duration: 199.580215ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:29:52.400835Z","caller":"traceutil/trace.go:171","msg":"trace[1391755741] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1809; }","duration":"155.374353ms","start":"2026-05-05T02:29:52.245432Z","end":"2026-05-05T02:29:52.400806Z","steps":["trace[1391755741] 'agreement among raft nodes before linearized reading' (duration: 154.940362ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:04.772935Z","caller":"traceutil/trace.go:171","msg":"trace[833094132] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"183.193435ms","start":"2026-05-05T02:30:04.589713Z","end":"2026-05-05T02:30:04.772907Z","steps":["trace[833094132] 'process raft request' (duration: 183.020091ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:04.778141Z","caller":"traceutil/trace.go:171","msg":"trace[937107401] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1984; }","duration":"147.459219ms","start":"2026-05-05T02:30:04.630661Z","end":"2026-05-05T02:30:04.77812Z","steps":["trace[937107401] 'read index received' (duration: 142.107052ms)","trace[937107401] 'applied index is now lower than readState.Index' (duration: 5.351357ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:30:04.778197Z","caller":"traceutil/trace.go:171","msg":"trace[518322392] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"187.782463ms","start":"2026-05-05T02:30:04.590394Z","end":"2026-05-05T02:30:04.778176Z","steps":["trace[518322392] 'process raft request' (duration: 187.688111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:04.778386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.712465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T02:30:04.778399Z","caller":"traceutil/trace.go:171","msg":"trace[725641047] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"188.467671ms","start":"2026-05-05T02:30:04.589916Z","end":"2026-05-05T02:30:04.778384Z","steps":["trace[725641047] 'process raft request' (duration: 188.05844ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:04.778427Z","caller":"traceutil/trace.go:171","msg":"trace[1709843148] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1917; }","duration":"147.802937ms","start":"2026-05-05T02:30:04.63061Z","end":"2026-05-05T02:30:04.778412Z","steps":["trace[1709843148] 'agreement among raft nodes before linearized reading' (duration: 147.600662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:41.324791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.331791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T02:30:41.324959Z","caller":"traceutil/trace.go:171","msg":"trace[756405206] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2126; }","duration":"203.604478ms","start":"2026-05-05T02:30:41.121322Z","end":"2026-05-05T02:30:41.324926Z","steps":["trace[756405206] 'range keys from in-memory index tree' (duration: 202.956972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:41.324982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.998581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T02:30:41.325106Z","caller":"traceutil/trace.go:171","msg":"trace[257732397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2126; }","duration":"295.176166ms","start":"2026-05-05T02:30:41.029905Z","end":"2026-05-05T02:30:41.325081Z","steps":["trace[257732397] 'range keys from in-memory index tree' (duration: 294.718284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:30:41.325121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.994606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T02:30:41.325262Z","caller":"traceutil/trace.go:171","msg":"trace[1342064392] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"278.164669ms","start":"2026-05-05T02:30:41.047056Z","end":"2026-05-05T02:30:41.325221Z","steps":["trace[1342064392] 'range keys from in-memory index tree' (duration: 277.751869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:41.511681Z","caller":"traceutil/trace.go:171","msg":"trace[1422808213] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"181.537174ms","start":"2026-05-05T02:30:41.330104Z","end":"2026-05-05T02:30:41.511641Z","steps":["trace[1422808213] 'process raft request' (duration: 181.306298ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:41.555421Z","caller":"traceutil/trace.go:171","msg":"trace[1462147062] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"225.246573ms","start":"2026-05-05T02:30:41.330143Z","end":"2026-05-05T02:30:41.55539Z","steps":["trace[1462147062] 'process raft request' (duration: 224.115685ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:30:41.555718Z","caller":"traceutil/trace.go:171","msg":"trace[7845258] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2203; }","duration":"111.542939ms","start":"2026-05-05T02:30:41.444081Z","end":"2026-05-05T02:30:41.555624Z","steps":["trace[7845258] 'read index received' (duration: 67.302625ms)","trace[7845258] 'applied index is now lower than readState.Index' (duration: 44.231164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:30:41.55583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.734704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:30:41.555881Z","caller":"traceutil/trace.go:171","msg":"trace[977040689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2129; }","duration":"111.858487ms","start":"2026-05-05T02:30:41.444007Z","end":"2026-05-05T02:30:41.555865Z","steps":["trace[977040689] 'agreement among raft nodes before linearized reading' (duration: 111.756965ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:53.960448Z","caller":"traceutil/trace.go:171","msg":"trace[1180722340] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"123.147651ms","start":"2026-05-05T02:31:53.837258Z","end":"2026-05-05T02:31:53.960406Z","steps":["trace[1180722340] 'process raft request' (duration: 111.452919ms)","trace[1180722340] 'compare' (duration: 11.096005ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:54.166143Z","caller":"traceutil/trace.go:171","msg":"trace[1489411542] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"200.720819ms","start":"2026-05-05T02:31:53.965387Z","end":"2026-05-05T02:31:54.166108Z","steps":["trace[1489411542] 'process raft request' (duration: 144.152052ms)","trace[1489411542] 'compare' (duration: 56.112245ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:31:54.166284Z","caller":"traceutil/trace.go:171","msg":"trace[176872795] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"199.984948ms","start":"2026-05-05T02:31:53.966249Z","end":"2026-05-05T02:31:54.166234Z","steps":["trace[176872795] 'process raft request' (duration: 199.874355ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:31:54.166283Z","caller":"traceutil/trace.go:171","msg":"trace[616790404] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"200.02045ms","start":"2026-05-05T02:31:53.966189Z","end":"2026-05-05T02:31:54.166209Z","steps":["trace[616790404] 'process raft request' (duration: 199.868426ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:34:24.096316Z","caller":"traceutil/trace.go:171","msg":"trace[1178986065] transaction","detail":"{read_only:false; response_revision:3547; number_of_response:1; }","duration":"218.355809ms","start":"2026-05-05T02:34:23.877932Z","end":"2026-05-05T02:34:24.096287Z","steps":["trace[1178986065] 'process raft request' (duration: 218.130673ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:34:24.096671Z","caller":"traceutil/trace.go:171","msg":"trace[1155063431] transaction","detail":"{read_only:false; response_revision:3548; number_of_response:1; }","duration":"185.643827ms","start":"2026-05-05T02:34:23.911012Z","end":"2026-05-05T02:34:24.096656Z","steps":["trace[1155063431] 'process raft request' (duration: 185.223826ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:34:24.096759Z","caller":"traceutil/trace.go:171","msg":"trace[493858903] transaction","detail":"{read_only:false; response_revision:3549; number_of_response:1; }","duration":"173.318349ms","start":"2026-05-05T02:34:23.923393Z","end":"2026-05-05T02:34:24.096711Z","steps":["trace[493858903] 'process raft request' (duration: 173.149825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:34:24.097226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.988273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4d6x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T02:34:24.097345Z","caller":"traceutil/trace.go:171","msg":"trace[635964882] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4d6x; range_end:; response_count:1; response_revision:3549; }","duration":"211.255769ms","start":"2026-05-05T02:34:23.886066Z","end":"2026-05-05T02:34:24.097321Z","steps":["trace[635964882] 'agreement among raft nodes before linearized reading' (duration: 210.782147ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:34:24.096683Z","caller":"traceutil/trace.go:171","msg":"trace[1747264846] linearizableReadLoop","detail":"{readStateIndex:3678; appliedIndex:3676; }","duration":"210.566472ms","start":"2026-05-05T02:34:23.886087Z","end":"2026-05-05T02:34:24.096654Z","steps":["trace[1747264846] 'read index received' (duration: 79.668764ms)","trace[1747264846] 'applied index is now lower than readState.Index' (duration: 130.893628ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:35:40.248214Z","caller":"traceutil/trace.go:171","msg":"trace[622743015] linearizableReadLoop","detail":"{readStateIndex:4741; appliedIndex:4739; }","duration":"106.65147ms","start":"2026-05-05T02:35:40.141535Z","end":"2026-05-05T02:35:40.248186Z","steps":["trace[622743015] 'read index received' (duration: 73.489059ms)","trace[622743015] 'applied index is now lower than readState.Index' (duration: 33.161061ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:35:40.248332Z","caller":"traceutil/trace.go:171","msg":"trace[336257624] transaction","detail":"{read_only:false; response_revision:4569; number_of_response:1; }","duration":"109.911706ms","start":"2026-05-05T02:35:40.138378Z","end":"2026-05-05T02:35:40.24829Z","steps":["trace[336257624] 'process raft request' (duration: 109.629899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:35:40.249303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.724467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2353100"} {"level":"info","ts":"2026-05-05T02:35:40.249376Z","caller":"traceutil/trace.go:171","msg":"trace[1417105932] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4569; }","duration":"107.879491ms","start":"2026-05-05T02:35:40.141483Z","end":"2026-05-05T02:35:40.249362Z","steps":["trace[1417105932] 'agreement among raft nodes before linearized reading' (duration: 106.884325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:35:56.562328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.517178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:35:56.562451Z","caller":"traceutil/trace.go:171","msg":"trace[1756437836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4944; }","duration":"117.648201ms","start":"2026-05-05T02:35:56.44478Z","end":"2026-05-05T02:35:56.562429Z","steps":["trace[1756437836] 'range keys from in-memory index tree' (duration: 117.432456ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:36:05.397679Z","caller":"traceutil/trace.go:171","msg":"trace[1993635459] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"117.701143ms","start":"2026-05-05T02:36:05.279944Z","end":"2026-05-05T02:36:05.397645Z","steps":["trace[1993635459] 'process raft request' (duration: 20.23782ms)","trace[1993635459] 'compare' (duration: 97.309739ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:36:05.421038Z","caller":"traceutil/trace.go:171","msg":"trace[1570392546] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"137.197972ms","start":"2026-05-05T02:36:05.283822Z","end":"2026-05-05T02:36:05.42102Z","steps":["trace[1570392546] 'process raft request' (duration: 137.039438ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:37:43.78893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2835} {"level":"info","ts":"2026-05-05T02:37:43.859841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2835,"took":"69.490568ms","hash":4171438264,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":33583104,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T02:37:43.859921Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4171438264,"revision":2835,"compact-revision":-1} {"level":"info","ts":"2026-05-05T02:41:52.34113Z","caller":"traceutil/trace.go:171","msg":"trace[1031652258] transaction","detail":"{read_only:false; response_revision:7846; number_of_response:1; }","duration":"178.594857ms","start":"2026-05-05T02:41:52.162497Z","end":"2026-05-05T02:41:52.341092Z","steps":["trace[1031652258] 'process raft request' (duration: 178.272758ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:04.602095Z","caller":"traceutil/trace.go:171","msg":"trace[1852304790] linearizableReadLoop","detail":"{readStateIndex:8185; appliedIndex:8184; }","duration":"157.325323ms","start":"2026-05-05T02:42:04.444745Z","end":"2026-05-05T02:42:04.602071Z","steps":["trace[1852304790] 'read index received' (duration: 157.149658ms)","trace[1852304790] 'applied index is now lower than readState.Index' (duration: 174.685µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:42:04.602152Z","caller":"traceutil/trace.go:171","msg":"trace[1261972181] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"219.11331ms","start":"2026-05-05T02:42:04.383005Z","end":"2026-05-05T02:42:04.602118Z","steps":["trace[1261972181] 'process raft request' (duration: 218.892774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:04.602246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.490908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:42:04.602287Z","caller":"traceutil/trace.go:171","msg":"trace[1730552743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7910; }","duration":"157.57611ms","start":"2026-05-05T02:42:04.444696Z","end":"2026-05-05T02:42:04.602272Z","steps":["trace[1730552743] 'agreement among raft nodes before linearized reading' (duration: 157.507368ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:04.648873Z","caller":"traceutil/trace.go:171","msg":"trace[1556199002] transaction","detail":"{read_only:false; response_revision:7911; number_of_response:1; }","duration":"173.13566ms","start":"2026-05-05T02:42:04.475715Z","end":"2026-05-05T02:42:04.648851Z","steps":["trace[1556199002] 'process raft request' (duration: 172.77848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:04.648924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.646062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T02:42:04.648983Z","caller":"traceutil/trace.go:171","msg":"trace[1321724577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7911; }","duration":"137.731265ms","start":"2026-05-05T02:42:04.511236Z","end":"2026-05-05T02:42:04.648967Z","steps":["trace[1321724577] 'agreement among raft nodes before linearized reading' (duration: 137.55046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:04.649007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.784416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-05T02:42:04.648976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.011949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-05T02:42:04.64904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.635817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T02:42:04.649064Z","caller":"traceutil/trace.go:171","msg":"trace[352583326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7911; }","duration":"137.857738ms","start":"2026-05-05T02:42:04.51119Z","end":"2026-05-05T02:42:04.649048Z","steps":["trace[352583326] 'agreement among raft nodes before linearized reading' (duration: 137.714414ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:04.649098Z","caller":"traceutil/trace.go:171","msg":"trace[1270267557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7911; }","duration":"142.72571ms","start":"2026-05-05T02:42:04.506356Z","end":"2026-05-05T02:42:04.649082Z","steps":["trace[1270267557] 'agreement among raft nodes before linearized reading' (duration: 142.577696ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:04.64915Z","caller":"traceutil/trace.go:171","msg":"trace[401040788] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7911; }","duration":"139.219075ms","start":"2026-05-05T02:42:04.509909Z","end":"2026-05-05T02:42:04.649128Z","steps":["trace[401040788] 'agreement among raft nodes before linearized reading' (duration: 138.854736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:04.649175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.007708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T02:42:04.649278Z","caller":"traceutil/trace.go:171","msg":"trace[790550983] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:7911; }","duration":"202.156963ms","start":"2026-05-05T02:42:04.447095Z","end":"2026-05-05T02:42:04.649251Z","steps":["trace[790550983] 'agreement among raft nodes before linearized reading' (duration: 201.947867ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:05.430571Z","caller":"traceutil/trace.go:171","msg":"trace[729412504] transaction","detail":"{read_only:false; response_revision:7919; number_of_response:1; }","duration":"114.267312ms","start":"2026-05-05T02:42:05.316277Z","end":"2026-05-05T02:42:05.430544Z","steps":["trace[729412504] 'process raft request' (duration: 98.316672ms)","trace[729412504] 'compare' (duration: 15.800276ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:42:31.847006Z","caller":"traceutil/trace.go:171","msg":"trace[1529788920] transaction","detail":"{read_only:false; response_revision:8112; number_of_response:1; }","duration":"301.246845ms","start":"2026-05-05T02:42:31.545716Z","end":"2026-05-05T02:42:31.846963Z","steps":["trace[1529788920] 'process raft request' (duration: 300.936537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:31.847255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:42:31.545697Z","time spent":"301.42842ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:42:31.898007Z","caller":"traceutil/trace.go:171","msg":"trace[471193185] linearizableReadLoop","detail":"{readStateIndex:8394; appliedIndex:8392; }","duration":"307.027322ms","start":"2026-05-05T02:42:31.59094Z","end":"2026-05-05T02:42:31.897967Z","steps":["trace[471193185] 'read index received' (duration: 255.751179ms)","trace[471193185] 'applied index is now lower than readState.Index' (duration: 51.274583ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:42:31.898097Z","caller":"traceutil/trace.go:171","msg":"trace[1156468041] transaction","detail":"{read_only:false; response_revision:8113; number_of_response:1; }","duration":"352.24133ms","start":"2026-05-05T02:42:31.545783Z","end":"2026-05-05T02:42:31.898024Z","steps":["trace[1156468041] 'process raft request' (duration: 351.969103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:31.898207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.248337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:42:31.89831Z","caller":"traceutil/trace.go:171","msg":"trace[981698981] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8113; }","duration":"307.415191ms","start":"2026-05-05T02:42:31.59087Z","end":"2026-05-05T02:42:31.898286Z","steps":["trace[981698981] 'agreement among raft nodes before linearized reading' (duration: 307.237246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:31.898368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:42:31.590841Z","time spent":"307.516994ms","remote":"127.0.0.1:35056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-05-05T02:42:31.89856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T02:42:31.545751Z","time spent":"352.473856ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T02:42:43.796006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6004} {"level":"info","ts":"2026-05-05T02:42:43.891051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6004,"took":"92.126085ms","hash":2182051956,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":22540288,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-05T02:42:43.891158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2182051956,"revision":6004,"compact-revision":2835} {"level":"info","ts":"2026-05-05T02:42:45.579123Z","caller":"traceutil/trace.go:171","msg":"trace[1731458831] transaction","detail":"{read_only:false; response_revision:8226; number_of_response:1; }","duration":"220.390165ms","start":"2026-05-05T02:42:45.358712Z","end":"2026-05-05T02:42:45.579102Z","steps":["trace[1731458831] 'process raft request' (duration: 220.233971ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:45.579151Z","caller":"traceutil/trace.go:171","msg":"trace[172483038] linearizableReadLoop","detail":"{readStateIndex:8514; appliedIndex:8514; }","duration":"142.045942ms","start":"2026-05-05T02:42:45.43706Z","end":"2026-05-05T02:42:45.579106Z","steps":["trace[172483038] 'read index received' (duration: 142.029501ms)","trace[172483038] 'applied index is now lower than readState.Index' (duration: 13.671µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:42:45.579366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.283508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4gdn8\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-05-05T02:42:45.579436Z","caller":"traceutil/trace.go:171","msg":"trace[738732888] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4gdn8; range_end:; response_count:1; response_revision:8226; }","duration":"142.412421ms","start":"2026-05-05T02:42:45.437011Z","end":"2026-05-05T02:42:45.579424Z","steps":["trace[738732888] 'agreement among raft nodes before linearized reading' (duration: 142.215766ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:45.640939Z","caller":"traceutil/trace.go:171","msg":"trace[245637011] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"203.683354ms","start":"2026-05-05T02:42:45.437231Z","end":"2026-05-05T02:42:45.640914Z","steps":["trace[245637011] 'process raft request' (duration: 203.511599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:45.641086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.441647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:42:45.641129Z","caller":"traceutil/trace.go:171","msg":"trace[1901884371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8227; }","duration":"200.499698ms","start":"2026-05-05T02:42:45.44062Z","end":"2026-05-05T02:42:45.641119Z","steps":["trace[1901884371] 'agreement among raft nodes before linearized reading' (duration: 200.375475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:42:45.641169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.691829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T02:42:45.641291Z","caller":"traceutil/trace.go:171","msg":"trace[2046231438] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:8227; }","duration":"142.878894ms","start":"2026-05-05T02:42:45.498383Z","end":"2026-05-05T02:42:45.641261Z","steps":["trace[2046231438] 'agreement among raft nodes before linearized reading' (duration: 142.588826ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:42:45.713748Z","caller":"traceutil/trace.go:171","msg":"trace[2059436051] transaction","detail":"{read_only:false; response_revision:8228; number_of_response:1; }","duration":"126.42296ms","start":"2026-05-05T02:42:45.587281Z","end":"2026-05-05T02:42:45.713704Z","steps":["trace[2059436051] 'process raft request' (duration: 105.6819ms)","trace[2059436051] 'compare' (duration: 20.473923ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:42:46.669125Z","caller":"traceutil/trace.go:171","msg":"trace[1622171610] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"146.215515ms","start":"2026-05-05T02:42:46.522606Z","end":"2026-05-05T02:42:46.668821Z","steps":["trace[1622171610] 'process raft request' (duration: 146.04033ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:43:53.518806Z","caller":"traceutil/trace.go:171","msg":"trace[322048256] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"141.906487ms","start":"2026-05-05T02:43:53.376863Z","end":"2026-05-05T02:43:53.51877Z","steps":["trace[322048256] 'process raft request' (duration: 51.554751ms)","trace[322048256] 'compare' (duration: 90.024998ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:44:04.338185Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243a.wal"} {"level":"info","ts":"2026-05-05T02:44:13.750761Z","caller":"traceutil/trace.go:171","msg":"trace[1516926707] linearizableReadLoop","detail":"{readStateIndex:9399; appliedIndex:9398; }","duration":"108.362893ms","start":"2026-05-05T02:44:13.642355Z","end":"2026-05-05T02:44:13.750718Z","steps":["trace[1516926707] 'read index received' (duration: 19.563128ms)","trace[1516926707] 'applied index is now lower than readState.Index' (duration: 88.798635ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:44:13.750784Z","caller":"traceutil/trace.go:171","msg":"trace[734024203] transaction","detail":"{read_only:false; response_revision:9088; number_of_response:1; }","duration":"125.421913ms","start":"2026-05-05T02:44:13.625329Z","end":"2026-05-05T02:44:13.750751Z","steps":["trace[734024203] 'process raft request' (duration: 36.645438ms)","trace[734024203] 'compare' (duration: 88.58936ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:44:13.751118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.734983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T02:44:13.751234Z","caller":"traceutil/trace.go:171","msg":"trace[937528619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9088; }","duration":"108.888287ms","start":"2026-05-05T02:44:13.642319Z","end":"2026-05-05T02:44:13.751208Z","steps":["trace[937528619] 'agreement among raft nodes before linearized reading' (duration: 108.560998ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:45:32.824106Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f9f905057b5b268","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T02:45:32.829917Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T02:45:32.830039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-05T02:47:37.049535Z","caller":"traceutil/trace.go:171","msg":"trace[2055709779] transaction","detail":"{read_only:false; response_revision:10813; number_of_response:1; }","duration":"117.521151ms","start":"2026-05-05T02:47:36.931987Z","end":"2026-05-05T02:47:37.049508Z","steps":["trace[2055709779] 'process raft request' (duration: 117.331966ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:47:37.059053Z","caller":"traceutil/trace.go:171","msg":"trace[1461884814] linearizableReadLoop","detail":"{readStateIndex:11175; appliedIndex:11173; }","duration":"124.409386ms","start":"2026-05-05T02:47:36.93462Z","end":"2026-05-05T02:47:37.059029Z","steps":["trace[1461884814] 'read index received' (duration: 114.800858ms)","trace[1461884814] 'applied index is now lower than readState.Index' (duration: 9.607568ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:47:37.059146Z","caller":"traceutil/trace.go:171","msg":"trace[1461295179] transaction","detail":"{read_only:false; response_revision:10814; number_of_response:1; }","duration":"127.079778ms","start":"2026-05-05T02:47:36.932022Z","end":"2026-05-05T02:47:37.059102Z","steps":["trace[1461295179] 'process raft request' (duration: 126.885443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:47:37.059443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.805487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-05T02:47:37.0595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.312056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.117\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T02:47:37.059562Z","caller":"traceutil/trace.go:171","msg":"trace[1522009779] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10814; }","duration":"124.983522ms","start":"2026-05-05T02:47:36.934554Z","end":"2026-05-05T02:47:37.059537Z","steps":["trace[1522009779] 'agreement among raft nodes before linearized reading' (duration: 124.626192ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:47:37.059613Z","caller":"traceutil/trace.go:171","msg":"trace[449576214] range","detail":"{range_begin:/registry/masterleases/199.19.213.117; range_end:; response_count:1; response_revision:10814; }","duration":"117.447469ms","start":"2026-05-05T02:47:36.94213Z","end":"2026-05-05T02:47:37.059577Z","steps":["trace[449576214] 'agreement among raft nodes before linearized reading' (duration: 117.12677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:47:37.059612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.843526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-05T02:47:37.05976Z","caller":"traceutil/trace.go:171","msg":"trace[372968713] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:10814; }","duration":"119.051922ms","start":"2026-05-05T02:47:36.940671Z","end":"2026-05-05T02:47:37.059723Z","steps":["trace[372968713] 'agreement among raft nodes before linearized reading' (duration: 118.562859ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:47:43.802542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8205} {"level":"info","ts":"2026-05-05T02:47:43.884499Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8205,"took":"79.229732ms","hash":559733563,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":26320896,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T02:47:43.884617Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":559733563,"revision":8205,"compact-revision":6004} {"level":"info","ts":"2026-05-05T02:52:43.810904Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10876} {"level":"info","ts":"2026-05-05T02:52:43.896103Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10876,"took":"81.905896ms","hash":708336433,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":27279360,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-05T02:52:43.896212Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":708336433,"revision":10876,"compact-revision":8205} {"level":"info","ts":"2026-05-05T02:56:52.319921Z","caller":"traceutil/trace.go:171","msg":"trace[1376972923] transaction","detail":"{read_only:false; response_revision:14248; number_of_response:1; }","duration":"160.847297ms","start":"2026-05-05T02:56:52.159042Z","end":"2026-05-05T02:56:52.319889Z","steps":["trace[1376972923] 'process raft request' (duration: 160.516117ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:56:52.321191Z","caller":"traceutil/trace.go:171","msg":"trace[240739996] linearizableReadLoop","detail":"{readStateIndex:14737; appliedIndex:14735; }","duration":"129.965723ms","start":"2026-05-05T02:56:52.191192Z","end":"2026-05-05T02:56:52.321158Z","steps":["trace[240739996] 'read index received' (duration: 128.448862ms)","trace[240739996] 'applied index is now lower than readState.Index' (duration: 1.515041ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:56:52.321323Z","caller":"traceutil/trace.go:171","msg":"trace[212756077] transaction","detail":"{read_only:false; response_revision:14249; number_of_response:1; }","duration":"161.774011ms","start":"2026-05-05T02:56:52.159499Z","end":"2026-05-05T02:56:52.321273Z","steps":["trace[212756077] 'process raft request' (duration: 161.434942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:56:52.321743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.534168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4d6x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T02:56:52.3218Z","caller":"traceutil/trace.go:171","msg":"trace[1024575320] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4d6x; range_end:; response_count:1; response_revision:14249; }","duration":"130.660972ms","start":"2026-05-05T02:56:52.191125Z","end":"2026-05-05T02:56:52.321786Z","steps":["trace[1024575320] 'agreement among raft nodes before linearized reading' (duration: 130.20013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T02:57:00.750744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.534832ms","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-05-05T02:57:00.750879Z","caller":"traceutil/trace.go:171","msg":"trace[474704263] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14311; }","duration":"231.740477ms","start":"2026-05-05T02:57:00.519112Z","end":"2026-05-05T02:57:00.750852Z","steps":["trace[474704263] 'range keys from in-memory index tree' (duration: 231.311037ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:57:00.865166Z","caller":"traceutil/trace.go:171","msg":"trace[1542852720] transaction","detail":"{read_only:false; response_revision:14312; number_of_response:1; }","duration":"109.967394ms","start":"2026-05-05T02:57:00.755155Z","end":"2026-05-05T02:57:00.865122Z","steps":["trace[1542852720] 'process raft request' (duration: 109.706747ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:57:00.877905Z","caller":"traceutil/trace.go:171","msg":"trace[2102698109] transaction","detail":"{read_only:false; response_revision:14313; number_of_response:1; }","duration":"122.701277ms","start":"2026-05-05T02:57:00.755151Z","end":"2026-05-05T02:57:00.877852Z","steps":["trace[2102698109] 'process raft request' (duration: 122.414549ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:57:23.467471Z","caller":"traceutil/trace.go:171","msg":"trace[370756600] transaction","detail":"{read_only:false; response_revision:14623; number_of_response:1; }","duration":"271.018097ms","start":"2026-05-05T02:57:23.196379Z","end":"2026-05-05T02:57:23.467397Z","steps":["trace[370756600] 'process raft request' (duration: 270.686928ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:57:43.8195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12907} {"level":"info","ts":"2026-05-05T02:57:43.900957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12907,"took":"78.111696ms","hash":1015187135,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":24866816,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-05T02:57:43.901022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1015187135,"revision":12907,"compact-revision":10876} {"level":"info","ts":"2026-05-05T02:58:49.564939Z","caller":"traceutil/trace.go:171","msg":"trace[2038656337] transaction","detail":"{read_only:false; response_revision:15434; number_of_response:1; }","duration":"134.22902ms","start":"2026-05-05T02:58:49.430669Z","end":"2026-05-05T02:58:49.564898Z","steps":["trace[2038656337] 'process raft request' (duration: 133.750487ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T02:58:49.657197Z","caller":"traceutil/trace.go:171","msg":"trace[279170291] linearizableReadLoop","detail":"{readStateIndex:15963; appliedIndex:15961; }","duration":"141.884656ms","start":"2026-05-05T02:58:49.515288Z","end":"2026-05-05T02:58:49.657172Z","steps":["trace[279170291] 'read index received' (duration: 49.386122ms)","trace[279170291] 'applied index is now lower than readState.Index' (duration: 92.497364ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T02:58:49.657451Z","caller":"traceutil/trace.go:171","msg":"trace[1266762091] transaction","detail":"{read_only:false; response_revision:15435; number_of_response:1; }","duration":"225.545212ms","start":"2026-05-05T02:58:49.431882Z","end":"2026-05-05T02:58:49.657427Z","steps":["trace[1266762091] 'process raft request' (duration: 211.38684ms)","trace[1266762091] 'compare' (duration: 13.725041ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T02:58:49.657659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.343979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-j4d6x\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-05-05T02:58:49.657734Z","caller":"traceutil/trace.go:171","msg":"trace[781264596] range","detail":"{range_begin:/registry/pods/openstack/keepalived-j4d6x; range_end:; response_count:1; response_revision:15435; }","duration":"142.511234ms","start":"2026-05-05T02:58:49.515205Z","end":"2026-05-05T02:58:49.657716Z","steps":["trace[781264596] 'agreement among raft nodes before linearized reading' (duration: 142.225926ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:01:32.243938Z","caller":"traceutil/trace.go:171","msg":"trace[434557545] linearizableReadLoop","detail":"{readStateIndex:17410; appliedIndex:17409; }","duration":"101.472949ms","start":"2026-05-05T03:01:32.142429Z","end":"2026-05-05T03:01:32.243902Z","steps":["trace[434557545] 'read index received' (duration: 101.207492ms)","trace[434557545] 'applied index is now lower than readState.Index' (duration: 263.987µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:01:32.24408Z","caller":"traceutil/trace.go:171","msg":"trace[1583227792] transaction","detail":"{read_only:false; response_revision:16842; number_of_response:1; }","duration":"107.358249ms","start":"2026-05-05T03:01:32.136677Z","end":"2026-05-05T03:01:32.244036Z","steps":["trace[1583227792] 'process raft request' (duration: 107.001399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:01:32.244202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.728347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:01:32.24434Z","caller":"traceutil/trace.go:171","msg":"trace[865728057] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:16842; }","duration":"101.941432ms","start":"2026-05-05T03:01:32.142362Z","end":"2026-05-05T03:01:32.244304Z","steps":["trace[865728057] 'agreement among raft nodes before linearized reading' (duration: 101.692455ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:02:43.833649Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14760} {"level":"info","ts":"2026-05-05T03:02:43.915096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14760,"took":"77.238075ms","hash":3640165654,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":32866304,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-05T03:02:43.915162Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3640165654,"revision":14760,"compact-revision":12907} {"level":"info","ts":"2026-05-05T03:04:12.381278Z","caller":"traceutil/trace.go:171","msg":"trace[1735066904] linearizableReadLoop","detail":"{readStateIndex:18586; appliedIndex:18585; }","duration":"132.291413ms","start":"2026-05-05T03:04:12.248961Z","end":"2026-05-05T03:04:12.381253Z","steps":["trace[1735066904] 'read index received' (duration: 66.410984ms)","trace[1735066904] 'applied index is now lower than readState.Index' (duration: 65.879279ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:04:12.381416Z","caller":"traceutil/trace.go:171","msg":"trace[1869590541] transaction","detail":"{read_only:false; response_revision:17979; number_of_response:1; }","duration":"133.414062ms","start":"2026-05-05T03:04:12.247992Z","end":"2026-05-05T03:04:12.381406Z","steps":["trace[1869590541] 'process raft request' (duration: 67.480212ms)","trace[1869590541] 'compare' (duration: 65.633202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:04:12.381821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.831057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-senlin-server\" ","response":"range_response_count:1 size:9598"} {"level":"info","ts":"2026-05-05T03:04:12.381855Z","caller":"traceutil/trace.go:171","msg":"trace[666167133] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-senlin-server; range_end:; response_count:1; response_revision:17979; }","duration":"132.914729ms","start":"2026-05-05T03:04:12.248932Z","end":"2026-05-05T03:04:12.381847Z","steps":["trace[666167133] 'agreement among raft nodes before linearized reading' (duration: 132.738514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:12.381905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.0027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3525"} {"level":"info","ts":"2026-05-05T03:04:12.382091Z","caller":"traceutil/trace.go:171","msg":"trace[740835201] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:17979; }","duration":"107.202145ms","start":"2026-05-05T03:04:12.274823Z","end":"2026-05-05T03:04:12.382025Z","steps":["trace[740835201] 'agreement among raft nodes before linearized reading' (duration: 106.716242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:12.840575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.149001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T03:04:12.84068Z","caller":"traceutil/trace.go:171","msg":"trace[140798028] linearizableReadLoop","detail":"{readStateIndex:18589; appliedIndex:18588; }","duration":"303.406823ms","start":"2026-05-05T03:04:12.537256Z","end":"2026-05-05T03:04:12.840663Z","steps":["trace[140798028] 'read index received' (duration: 155.046997ms)","trace[140798028] 'applied index is now lower than readState.Index' (duration: 148.358786ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:04:12.84087Z","caller":"traceutil/trace.go:171","msg":"trace[2022237469] transaction","detail":"{read_only:false; response_revision:17982; number_of_response:1; }","duration":"303.67612ms","start":"2026-05-05T03:04:12.537152Z","end":"2026-05-05T03:04:12.840828Z","steps":["trace[2022237469] 'process raft request' (duration: 155.13591ms)","trace[2022237469] 'compare' (duration: 148.015866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:04:12.840947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.68446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-senlin-server\" ","response":"range_response_count:1 size:9598"} {"level":"info","ts":"2026-05-05T03:04:12.841021Z","caller":"traceutil/trace.go:171","msg":"trace[1180139100] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-senlin-server; range_end:; response_count:1; response_revision:17982; }","duration":"303.796293ms","start":"2026-05-05T03:04:12.537212Z","end":"2026-05-05T03:04:12.841009Z","steps":["trace[1180139100] 'agreement among raft nodes before linearized reading' (duration: 303.607318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:12.841084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:04:12.537135Z","time spent":"303.833614ms","remote":"127.0.0.1:50234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:04:12.84109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:04:12.537193Z","time spent":"303.885546ms","remote":"127.0.0.1:35066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":9620,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-senlin-server\" "} {"level":"info","ts":"2026-05-05T03:04:12.861919Z","caller":"traceutil/trace.go:171","msg":"trace[490429596] transaction","detail":"{read_only:false; response_revision:17983; number_of_response:1; }","duration":"320.917255ms","start":"2026-05-05T03:04:12.540955Z","end":"2026-05-05T03:04:12.861872Z","steps":["trace[490429596] 'process raft request' (duration: 320.592896ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:04:12.861934Z","caller":"traceutil/trace.go:171","msg":"trace[1443983841] transaction","detail":"{read_only:false; response_revision:17984; number_of_response:1; }","duration":"218.713815ms","start":"2026-05-05T03:04:12.64318Z","end":"2026-05-05T03:04:12.861894Z","steps":["trace[1443983841] 'process raft request' (duration: 218.575472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:12.862148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:04:12.540935Z","time spent":"321.082189ms","remote":"127.0.0.1:34650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:04:12.862271Z","caller":"traceutil/trace.go:171","msg":"trace[1652563193] transaction","detail":"{read_only:false; response_revision:17985; number_of_response:1; }","duration":"208.817607ms","start":"2026-05-05T03:04:12.653403Z","end":"2026-05-05T03:04:12.862221Z","steps":["trace[1652563193] 'process raft request' (duration: 208.418596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:12.862579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.551183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:04:12.862637Z","caller":"traceutil/trace.go:171","msg":"trace[1161084903] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:17985; }","duration":"257.675176ms","start":"2026-05-05T03:04:12.604948Z","end":"2026-05-05T03:04:12.862623Z","steps":["trace[1161084903] 'agreement among raft nodes before linearized reading' (duration: 257.570493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:12.862685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.021977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jvhx4\" ","response":"range_response_count:1 size:20872"} {"level":"info","ts":"2026-05-05T03:04:12.862737Z","caller":"traceutil/trace.go:171","msg":"trace[605939452] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jvhx4; range_end:; response_count:1; response_revision:17985; }","duration":"188.114711ms","start":"2026-05-05T03:04:12.674609Z","end":"2026-05-05T03:04:12.862724Z","steps":["trace[605939452] 'agreement among raft nodes before linearized reading' (duration: 187.914854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:12.863974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.019208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2368094"} {"level":"info","ts":"2026-05-05T03:04:12.865581Z","caller":"traceutil/trace.go:171","msg":"trace[746317777] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:17985; }","duration":"101.196502ms","start":"2026-05-05T03:04:12.762873Z","end":"2026-05-05T03:04:12.86407Z","steps":["trace[746317777] 'agreement among raft nodes before linearized reading' (duration: 99.376333ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:04:13.208352Z","caller":"traceutil/trace.go:171","msg":"trace[934863527] linearizableReadLoop","detail":"{readStateIndex:18593; appliedIndex:18592; }","duration":"325.15408ms","start":"2026-05-05T03:04:12.883169Z","end":"2026-05-05T03:04:13.208323Z","steps":["trace[934863527] 'read index received' (duration: 324.936244ms)","trace[934863527] 'applied index is now lower than readState.Index' (duration: 216.666µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:04:13.209021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.714362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-05-05T03:04:13.209003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.847842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-05-05T03:04:13.211576Z","caller":"traceutil/trace.go:171","msg":"trace[1809149905] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:17986; }","duration":"310.462623ms","start":"2026-05-05T03:04:12.901076Z","end":"2026-05-05T03:04:13.211538Z","steps":["trace[1809149905] 'agreement among raft nodes before linearized reading' (duration: 307.667478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:13.211726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:04:12.901065Z","time spent":"310.634758ms","remote":"127.0.0.1:34662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-05-05T03:04:13.20908Z","caller":"traceutil/trace.go:171","msg":"trace[1194697366] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17986; }","duration":"226.830564ms","start":"2026-05-05T03:04:12.982233Z","end":"2026-05-05T03:04:13.209063Z","steps":["trace[1194697366] 'agreement among raft nodes before linearized reading' (duration: 226.66808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:13.208991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.774086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-senlin.18ac8c55a6ca7f40\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-05-05T03:04:13.212476Z","caller":"traceutil/trace.go:171","msg":"trace[72709820] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-senlin.18ac8c55a6ca7f40; range_end:; response_count:1; response_revision:17986; }","duration":"329.292231ms","start":"2026-05-05T03:04:12.883126Z","end":"2026-05-05T03:04:13.212418Z","steps":["trace[72709820] 'agreement among raft nodes before linearized reading' (duration: 325.606202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:13.213316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:04:12.883108Z","time spent":"330.185705ms","remote":"127.0.0.1:34650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":769,"request content":"key:\"/registry/events/openstack/rabbitmq-senlin.18ac8c55a6ca7f40\" "} {"level":"info","ts":"2026-05-05T03:04:13.209204Z","caller":"traceutil/trace.go:171","msg":"trace[857405237] transaction","detail":"{read_only:false; response_revision:17986; number_of_response:1; }","duration":"328.524961ms","start":"2026-05-05T03:04:12.880545Z","end":"2026-05-05T03:04:13.20907Z","steps":["trace[857405237] 'process raft request' (duration: 327.603756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:13.213805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:04:12.880532Z","time spent":"333.183386ms","remote":"127.0.0.1:34754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20987,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:04:13.20934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.736402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-05-05T03:04:13.213904Z","caller":"traceutil/trace.go:171","msg":"trace[1507292978] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17986; }","duration":"151.278704ms","start":"2026-05-05T03:04:13.062589Z","end":"2026-05-05T03:04:13.213868Z","steps":["trace[1507292978] 'agreement among raft nodes before linearized reading' (duration: 146.662079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:13.20945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.369621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T03:04:13.214289Z","caller":"traceutil/trace.go:171","msg":"trace[1723516134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17986; }","duration":"238.197561ms","start":"2026-05-05T03:04:12.976047Z","end":"2026-05-05T03:04:13.214244Z","steps":["trace[1723516134] 'agreement among raft nodes before linearized reading' (duration: 233.32614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:04:13.209494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.433643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T03:04:13.214477Z","caller":"traceutil/trace.go:171","msg":"trace[1492973730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17986; }","duration":"238.454328ms","start":"2026-05-05T03:04:12.976003Z","end":"2026-05-05T03:04:13.214458Z","steps":["trace[1492973730] 'agreement among raft nodes before linearized reading' (duration: 233.422162ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:06:30.209998Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f9f905057b5b268","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T03:06:30.21559Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-05T03:06:30.21574Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-05T03:07:43.840412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17304} {"level":"info","ts":"2026-05-05T03:07:43.949505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17304,"took":"104.632957ms","hash":1944678576,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":35766272,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-05T03:07:43.949587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1944678576,"revision":17304,"compact-revision":14760} {"level":"info","ts":"2026-05-05T03:11:38.122062Z","caller":"traceutil/trace.go:171","msg":"trace[1752524775] linearizableReadLoop","detail":"{readStateIndex:22427; appliedIndex:22426; }","duration":"262.723228ms","start":"2026-05-05T03:11:37.859293Z","end":"2026-05-05T03:11:38.122016Z","steps":["trace[1752524775] 'read index received' (duration: 262.447821ms)","trace[1752524775] 'applied index is now lower than readState.Index' (duration: 273.117µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:11:38.122217Z","caller":"traceutil/trace.go:171","msg":"trace[153674326] transaction","detail":"{read_only:false; response_revision:21708; number_of_response:1; }","duration":"267.701342ms","start":"2026-05-05T03:11:37.854464Z","end":"2026-05-05T03:11:38.122165Z","steps":["trace[153674326] 'process raft request' (duration: 267.284131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:11:38.122463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.125529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-05-05T03:11:38.122581Z","caller":"traceutil/trace.go:171","msg":"trace[1738694855] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:21708; }","duration":"263.279783ms","start":"2026-05-05T03:11:37.859273Z","end":"2026-05-05T03:11:38.122553Z","steps":["trace[1738694855] 'agreement among raft nodes before linearized reading' (duration: 262.959564ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:11:38.172417Z","caller":"traceutil/trace.go:171","msg":"trace[1711273461] transaction","detail":"{read_only:false; response_revision:21709; number_of_response:1; }","duration":"312.925691ms","start":"2026-05-05T03:11:37.859447Z","end":"2026-05-05T03:11:38.172373Z","steps":["trace[1711273461] 'process raft request' (duration: 300.794212ms)","trace[1711273461] 'compare' (duration: 11.730849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:11:38.172685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:11:37.859433Z","time spent":"313.068636ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:11:38.172867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.012067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T03:11:38.172971Z","caller":"traceutil/trace.go:171","msg":"trace[2096949178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21710; }","duration":"305.156351ms","start":"2026-05-05T03:11:37.867792Z","end":"2026-05-05T03:11:38.172949Z","steps":["trace[2096949178] 'agreement among raft nodes before linearized reading' (duration: 304.859503ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:11:38.17291Z","caller":"traceutil/trace.go:171","msg":"trace[1283319328] transaction","detail":"{read_only:false; response_revision:21710; number_of_response:1; }","duration":"147.462595ms","start":"2026-05-05T03:11:38.025358Z","end":"2026-05-05T03:11:38.172821Z","steps":["trace[1283319328] 'process raft request' (duration: 146.743666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:11:38.17303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:11:37.867779Z","time spent":"305.241833ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T03:11:38.172945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.103433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T03:11:38.17323Z","caller":"traceutil/trace.go:171","msg":"trace[1877814920] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21710; }","duration":"307.342229ms","start":"2026-05-05T03:11:37.865784Z","end":"2026-05-05T03:11:38.173126Z","steps":["trace[1877814920] 'agreement among raft nodes before linearized reading' (duration: 306.920707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:11:38.173362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:11:37.86577Z","time spent":"307.549295ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T03:11:38.497924Z","caller":"traceutil/trace.go:171","msg":"trace[506595023] transaction","detail":"{read_only:false; response_revision:21714; number_of_response:1; }","duration":"295.899898ms","start":"2026-05-05T03:11:38.201984Z","end":"2026-05-05T03:11:38.497883Z","steps":["trace[506595023] 'process raft request' (duration: 295.645642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:11:38.803099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.18463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:11:38.803254Z","caller":"traceutil/trace.go:171","msg":"trace[204044708] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:21715; }","duration":"178.420136ms","start":"2026-05-05T03:11:38.624791Z","end":"2026-05-05T03:11:38.803211Z","steps":["trace[204044708] 'count revisions from in-memory index tree' (duration: 178.028115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:11:38.803425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.296843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-05T03:11:38.803547Z","caller":"traceutil/trace.go:171","msg":"trace[662516530] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:21715; }","duration":"192.481438ms","start":"2026-05-05T03:11:38.611033Z","end":"2026-05-05T03:11:38.803515Z","steps":["trace[662516530] 'range keys from in-memory index tree' (duration: 192.083618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:11:38.8123Z","caller":"traceutil/trace.go:171","msg":"trace[2099814111] transaction","detail":"{read_only:false; response_revision:21716; number_of_response:1; }","duration":"115.132768ms","start":"2026-05-05T03:11:38.697079Z","end":"2026-05-05T03:11:38.812211Z","steps":["trace[2099814111] 'process raft request' (duration: 114.764598ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:12:43.846951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20046} {"level":"info","ts":"2026-05-05T03:12:43.973797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20046,"took":"122.853267ms","hash":1015975496,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":32071680,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T03:12:43.973871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1015975496,"revision":20046,"compact-revision":17304} {"level":"info","ts":"2026-05-05T03:15:25.313513Z","caller":"traceutil/trace.go:171","msg":"trace[1541777612] transaction","detail":"{read_only:false; response_revision:24139; number_of_response:1; }","duration":"147.031498ms","start":"2026-05-05T03:15:25.166446Z","end":"2026-05-05T03:15:25.313477Z","steps":["trace[1541777612] 'process raft request' (duration: 146.819202ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:15:25.509695Z","caller":"traceutil/trace.go:171","msg":"trace[1187809650] transaction","detail":"{read_only:false; response_revision:24140; number_of_response:1; }","duration":"145.655668ms","start":"2026-05-05T03:15:25.363991Z","end":"2026-05-05T03:15:25.509647Z","steps":["trace[1187809650] 'process raft request' (duration: 131.025712ms)","trace[1187809650] 'compare' (duration: 14.142283ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:17:43.855736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22311} {"level":"info","ts":"2026-05-05T03:17:43.965027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22311,"took":"105.313507ms","hash":1449464156,"current-db-size-bytes":57626624,"current-db-size":"58 MB","current-db-size-in-use-bytes":43266048,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-05T03:17:43.965106Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1449464156,"revision":22311,"compact-revision":20046} {"level":"warn","ts":"2026-05-05T03:18:46.78796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.007027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-05T03:18:46.788087Z","caller":"traceutil/trace.go:171","msg":"trace[775487658] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:26155; }","duration":"132.541671ms","start":"2026-05-05T03:18:46.655519Z","end":"2026-05-05T03:18:46.788061Z","steps":["trace[775487658] 'range keys from in-memory index tree' (duration: 131.77232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:46.788123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.293194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-05T03:18:46.788247Z","caller":"traceutil/trace.go:171","msg":"trace[2115075740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26155; }","duration":"169.442668ms","start":"2026-05-05T03:18:46.618774Z","end":"2026-05-05T03:18:46.788217Z","steps":["trace[2115075740] 'range keys from in-memory index tree' (duration: 169.035666ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:47.15204Z","caller":"traceutil/trace.go:171","msg":"trace[2127438955] linearizableReadLoop","detail":"{readStateIndex:27003; appliedIndex:27002; }","duration":"324.173692ms","start":"2026-05-05T03:18:46.827825Z","end":"2026-05-05T03:18:47.151999Z","steps":["trace[2127438955] 'read index received' (duration: 323.254697ms)","trace[2127438955] 'applied index is now lower than readState.Index' (duration: 917.095µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:47.152702Z","caller":"traceutil/trace.go:171","msg":"trace[962602827] transaction","detail":"{read_only:false; response_revision:26156; number_of_response:1; }","duration":"354.544424ms","start":"2026-05-05T03:18:46.79811Z","end":"2026-05-05T03:18:47.152654Z","steps":["trace[962602827] 'process raft request' (duration: 353.637549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.15289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.964295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-05T03:18:47.152936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:46.798094Z","time spent":"354.722471ms","remote":"127.0.0.1:34838","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-05-05T03:18:47.153004Z","caller":"traceutil/trace.go:171","msg":"trace[562235813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26156; }","duration":"325.12626ms","start":"2026-05-05T03:18:46.827837Z","end":"2026-05-05T03:18:47.152964Z","steps":["trace[562235813] 'agreement among raft nodes before linearized reading' (duration: 324.834931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.153078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:46.827811Z","time spent":"325.232613ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T03:18:47.15337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.510391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T03:18:47.153408Z","caller":"traceutil/trace.go:171","msg":"trace[1714656792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26156; }","duration":"325.632744ms","start":"2026-05-05T03:18:46.827767Z","end":"2026-05-05T03:18:47.1534Z","steps":["trace[1714656792] 'agreement among raft nodes before linearized reading' (duration: 325.48532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.153476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:46.827735Z","time spent":"325.732777ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T03:18:47.153899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.472065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-05T03:18:47.153917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.004512ms","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-05-05T03:18:47.153964Z","caller":"traceutil/trace.go:171","msg":"trace[1940033887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26156; }","duration":"223.097974ms","start":"2026-05-05T03:18:46.930851Z","end":"2026-05-05T03:18:47.153949Z","steps":["trace[1940033887] 'agreement among raft nodes before linearized reading' (duration: 222.884748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:47.153983Z","caller":"traceutil/trace.go:171","msg":"trace[1944141656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26156; }","duration":"322.538946ms","start":"2026-05-05T03:18:46.831396Z","end":"2026-05-05T03:18:47.153935Z","steps":["trace[1944141656] 'agreement among raft nodes before linearized reading' (duration: 322.437953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.15402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:46.83137Z","time spent":"322.639989ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T03:18:47.15418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.777447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T03:18:47.154222Z","caller":"traceutil/trace.go:171","msg":"trace[266151443] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26156; }","duration":"221.828789ms","start":"2026-05-05T03:18:46.93238Z","end":"2026-05-05T03:18:47.154208Z","steps":["trace[266151443] 'agreement among raft nodes before linearized reading' (duration: 221.725536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.154303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.220442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-05-05T03:18:47.154371Z","caller":"traceutil/trace.go:171","msg":"trace[75128926] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26156; }","duration":"325.310065ms","start":"2026-05-05T03:18:46.829049Z","end":"2026-05-05T03:18:47.15436Z","steps":["trace[75128926] 'agreement among raft nodes before linearized reading' (duration: 325.14926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.15449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:46.82904Z","time spent":"325.415207ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T03:18:47.503536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.937344ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-05T03:18:47.503608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.158814Z","time spent":"344.790652ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T03:18:47.563569Z","caller":"traceutil/trace.go:171","msg":"trace[196504873] linearizableReadLoop","detail":"{readStateIndex:27010; appliedIndex:27003; }","duration":"374.580918ms","start":"2026-05-05T03:18:47.188947Z","end":"2026-05-05T03:18:47.563527Z","steps":["trace[196504873] 'read index received' (duration: 20.671231ms)","trace[196504873] 'applied index is now lower than readState.Index' (duration: 353.908297ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:47.563941Z","caller":"traceutil/trace.go:171","msg":"trace[1323686941] transaction","detail":"{read_only:false; response_revision:26157; number_of_response:1; }","duration":"404.884879ms","start":"2026-05-05T03:18:47.159041Z","end":"2026-05-05T03:18:47.563926Z","steps":["trace[1323686941] 'process raft request' (duration: 404.039816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.564086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.159021Z","time spent":"404.962701ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:47.564325Z","caller":"traceutil/trace.go:171","msg":"trace[875663117] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"405.180677ms","start":"2026-05-05T03:18:47.159129Z","end":"2026-05-05T03:18:47.564309Z","steps":["trace[875663117] 'process raft request' (duration: 404.175589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.564394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.159118Z","time spent":"405.241578ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:47.564415Z","caller":"traceutil/trace.go:171","msg":"trace[506748764] transaction","detail":"{read_only:false; response_revision:26163; number_of_response:1; }","duration":"331.882919ms","start":"2026-05-05T03:18:47.232475Z","end":"2026-05-05T03:18:47.564358Z","steps":["trace[506748764] 'process raft request' (duration: 331.491938ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:47.564586Z","caller":"traceutil/trace.go:171","msg":"trace[473113152] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"403.84431ms","start":"2026-05-05T03:18:47.160713Z","end":"2026-05-05T03:18:47.564557Z","steps":["trace[473113152] 'process raft request' (duration: 402.76822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.564729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.786613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"warn","ts":"2026-05-05T03:18:47.564738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.160701Z","time spent":"403.952174ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:18:47.564422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.078971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:18:47.564786Z","caller":"traceutil/trace.go:171","msg":"trace[1244116460] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:26164; }","duration":"375.881866ms","start":"2026-05-05T03:18:47.18889Z","end":"2026-05-05T03:18:47.564772Z","steps":["trace[1244116460] 'agreement among raft nodes before linearized reading' (duration: 375.689499ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:47.564818Z","caller":"traceutil/trace.go:171","msg":"trace[1526210966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26164; }","duration":"120.544945ms","start":"2026-05-05T03:18:47.444252Z","end":"2026-05-05T03:18:47.564797Z","steps":["trace[1526210966] 'agreement among raft nodes before linearized reading' (duration: 119.975788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.564731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.23245Z","time spent":"332.127286ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:47.564838Z","caller":"traceutil/trace.go:171","msg":"trace[196162962] transaction","detail":"{read_only:false; response_revision:26160; number_of_response:1; }","duration":"405.446556ms","start":"2026-05-05T03:18:47.15933Z","end":"2026-05-05T03:18:47.564777Z","steps":["trace[196162962] 'process raft request' (duration: 404.073877ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:47.564868Z","caller":"traceutil/trace.go:171","msg":"trace[726325215] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"404.1785ms","start":"2026-05-05T03:18:47.160661Z","end":"2026-05-05T03:18:47.56484Z","steps":["trace[726325215] 'process raft request' (duration: 402.779371ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:47.564893Z","caller":"traceutil/trace.go:171","msg":"trace[951524150] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"405.706312ms","start":"2026-05-05T03:18:47.159157Z","end":"2026-05-05T03:18:47.564863Z","steps":["trace[951524150] 'process raft request' (duration: 404.196549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:47.564823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.188873Z","time spent":"375.941928ms","remote":"127.0.0.1:35056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11857,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-05T03:18:47.565045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.160651Z","time spent":"404.301354ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:18:47.565057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.159131Z","time spent":"405.852236ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:18:47.565083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:47.159321Z","time spent":"405.639531ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:52.693016Z","caller":"traceutil/trace.go:171","msg":"trace[367070585] transaction","detail":"{read_only:false; response_revision:26204; number_of_response:1; }","duration":"173.161933ms","start":"2026-05-05T03:18:52.519821Z","end":"2026-05-05T03:18:52.692983Z","steps":["trace[367070585] 'process raft request' (duration: 172.888895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:52.910203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.282072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:18:52.910296Z","caller":"traceutil/trace.go:171","msg":"trace[1518540522] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26204; }","duration":"128.440117ms","start":"2026-05-05T03:18:52.781833Z","end":"2026-05-05T03:18:52.910273Z","steps":["trace[1518540522] 'range keys from in-memory index tree' (duration: 128.18953ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:53.505798Z","caller":"traceutil/trace.go:171","msg":"trace[174241069] transaction","detail":"{read_only:false; response_revision:26206; number_of_response:1; }","duration":"255.279248ms","start":"2026-05-05T03:18:53.250481Z","end":"2026-05-05T03:18:53.50576Z","steps":["trace[174241069] 'process raft request' (duration: 255.023641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:54.247959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.130794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418684"} {"level":"info","ts":"2026-05-05T03:18:54.248065Z","caller":"traceutil/trace.go:171","msg":"trace[1112126300] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26216; }","duration":"244.30743ms","start":"2026-05-05T03:18:54.003723Z","end":"2026-05-05T03:18:54.24803Z","steps":["trace[1112126300] 'range keys from in-memory index tree' (duration: 243.796686ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:54.291341Z","caller":"traceutil/trace.go:171","msg":"trace[636398723] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"115.898655ms","start":"2026-05-05T03:18:54.175404Z","end":"2026-05-05T03:18:54.291302Z","steps":["trace[636398723] 'process raft request' (duration: 115.667718ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:54.837204Z","caller":"traceutil/trace.go:171","msg":"trace[1905549230] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"183.77457ms","start":"2026-05-05T03:18:54.653391Z","end":"2026-05-05T03:18:54.837166Z","steps":["trace[1905549230] 'process raft request' (duration: 183.302056ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:54.837747Z","caller":"traceutil/trace.go:171","msg":"trace[611513874] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"161.496665ms","start":"2026-05-05T03:18:54.676221Z","end":"2026-05-05T03:18:54.837717Z","steps":["trace[611513874] 'process raft request' (duration: 161.234677ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:54.837924Z","caller":"traceutil/trace.go:171","msg":"trace[2084245212] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"137.905722ms","start":"2026-05-05T03:18:54.699966Z","end":"2026-05-05T03:18:54.837872Z","steps":["trace[2084245212] 'process raft request' (duration: 137.685956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:55.180824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.3033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18256"} {"level":"info","ts":"2026-05-05T03:18:55.180927Z","caller":"traceutil/trace.go:171","msg":"trace[1102202963] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26220; }","duration":"150.482555ms","start":"2026-05-05T03:18:55.03042Z","end":"2026-05-05T03:18:55.180903Z","steps":["trace[1102202963] 'range keys from in-memory index tree' (duration: 150.053123ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:55.868319Z","caller":"traceutil/trace.go:171","msg":"trace[1160515782] linearizableReadLoop","detail":"{readStateIndex:27071; appliedIndex:27070; }","duration":"215.881951ms","start":"2026-05-05T03:18:55.652403Z","end":"2026-05-05T03:18:55.868285Z","steps":["trace[1160515782] 'read index received' (duration: 215.637355ms)","trace[1160515782] 'applied index is now lower than readState.Index' (duration: 243.396µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:55.868423Z","caller":"traceutil/trace.go:171","msg":"trace[196679492] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"226.900912ms","start":"2026-05-05T03:18:55.641465Z","end":"2026-05-05T03:18:55.868366Z","steps":["trace[196679492] 'process raft request' (duration: 226.601643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:55.868676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.867211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-05T03:18:55.868707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.262312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T03:18:55.868731Z","caller":"traceutil/trace.go:171","msg":"trace[165066564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26222; }","duration":"153.989604ms","start":"2026-05-05T03:18:55.714728Z","end":"2026-05-05T03:18:55.868717Z","steps":["trace[165066564] 'agreement among raft nodes before linearized reading' (duration: 153.752648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:55.868765Z","caller":"traceutil/trace.go:171","msg":"trace[1336161313] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26222; }","duration":"216.377445ms","start":"2026-05-05T03:18:55.65237Z","end":"2026-05-05T03:18:55.868748Z","steps":["trace[1336161313] 'agreement among raft nodes before linearized reading' (duration: 216.085347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:55.868864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.529634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-05T03:18:55.868934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.85242ms","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-05-05T03:18:55.868981Z","caller":"traceutil/trace.go:171","msg":"trace[890652772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26222; }","duration":"153.962133ms","start":"2026-05-05T03:18:55.715006Z","end":"2026-05-05T03:18:55.868968Z","steps":["trace[890652772] 'agreement among raft nodes before linearized reading' (duration: 153.82782ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:55.868985Z","caller":"traceutil/trace.go:171","msg":"trace[1505683514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26222; }","duration":"152.695658ms","start":"2026-05-05T03:18:55.716264Z","end":"2026-05-05T03:18:55.868959Z","steps":["trace[1505683514] 'agreement among raft nodes before linearized reading' (duration: 152.284437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:55.869067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.891684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T03:18:55.869152Z","caller":"traceutil/trace.go:171","msg":"trace[2124567236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26222; }","duration":"153.013857ms","start":"2026-05-05T03:18:55.716117Z","end":"2026-05-05T03:18:55.869131Z","steps":["trace[2124567236] 'agreement among raft nodes before linearized reading' (duration: 152.672427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:55.869378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.653135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-05-05T03:18:55.86942Z","caller":"traceutil/trace.go:171","msg":"trace[1414685509] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26222; }","duration":"153.696767ms","start":"2026-05-05T03:18:55.715712Z","end":"2026-05-05T03:18:55.869409Z","steps":["trace[1414685509] 'agreement among raft nodes before linearized reading' (duration: 153.09578ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:56.198416Z","caller":"traceutil/trace.go:171","msg":"trace[1455706674] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"322.919797ms","start":"2026-05-05T03:18:55.875452Z","end":"2026-05-05T03:18:56.198372Z","steps":["trace[1455706674] 'process raft request' (duration: 241.499371ms)","trace[1455706674] 'compare' (duration: 81.073916ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:56.198489Z","caller":"traceutil/trace.go:171","msg":"trace[1544851026] transaction","detail":"{read_only:false; response_revision:26225; number_of_response:1; }","duration":"322.770073ms","start":"2026-05-05T03:18:55.875683Z","end":"2026-05-05T03:18:56.198453Z","steps":["trace[1544851026] 'process raft request' (duration: 322.593458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:56.198627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:55.87567Z","time spent":"322.861646ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:18:56.198634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:55.875435Z","time spent":"323.085362ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:56.198705Z","caller":"traceutil/trace.go:171","msg":"trace[288884197] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"322.988629ms","start":"2026-05-05T03:18:55.875686Z","end":"2026-05-05T03:18:56.198675Z","steps":["trace[288884197] 'process raft request' (duration: 322.731081ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:56.198892Z","caller":"traceutil/trace.go:171","msg":"trace[2021232522] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"323.122074ms","start":"2026-05-05T03:18:55.875752Z","end":"2026-05-05T03:18:56.198874Z","steps":["trace[2021232522] 'process raft request' (duration: 322.840626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:56.198894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:55.875663Z","time spent":"323.124643ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:18:56.199142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:55.875725Z","time spent":"323.37681ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:56.199233Z","caller":"traceutil/trace.go:171","msg":"trace[1182923634] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"264.118007ms","start":"2026-05-05T03:18:55.935103Z","end":"2026-05-05T03:18:56.199221Z","steps":["trace[1182923634] 'process raft request' (duration: 264.023644ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:56.199283Z","caller":"traceutil/trace.go:171","msg":"trace[330507616] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"323.109913ms","start":"2026-05-05T03:18:55.876141Z","end":"2026-05-05T03:18:56.199251Z","steps":["trace[330507616] 'process raft request' (duration: 322.64662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:56.199436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:55.875942Z","time spent":"323.409701ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:56.199509Z","caller":"traceutil/trace.go:171","msg":"trace[2123270678] linearizableReadLoop","detail":"{readStateIndex:27080; appliedIndex:27072; }","duration":"232.902029ms","start":"2026-05-05T03:18:55.966591Z","end":"2026-05-05T03:18:56.199493Z","steps":["trace[2123270678] 'read index received' (duration: 150.458023ms)","trace[2123270678] 'applied index is now lower than readState.Index' (duration: 82.442626ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:56.199528Z","caller":"traceutil/trace.go:171","msg":"trace[1397494856] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"262.204683ms","start":"2026-05-05T03:18:55.937305Z","end":"2026-05-05T03:18:56.19951Z","steps":["trace[1397494856] 'process raft request' (duration: 262.11342ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:56.199563Z","caller":"traceutil/trace.go:171","msg":"trace[430678446] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"263.794667ms","start":"2026-05-05T03:18:55.935722Z","end":"2026-05-05T03:18:56.199516Z","steps":["trace[430678446] 'process raft request' (duration: 263.458737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:56.19963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.041894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:18:56.19967Z","caller":"traceutil/trace.go:171","msg":"trace[1275666141] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26231; }","duration":"233.132116ms","start":"2026-05-05T03:18:55.966528Z","end":"2026-05-05T03:18:56.19966Z","steps":["trace[1275666141] 'agreement among raft nodes before linearized reading' (duration: 233.014562ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:57.093924Z","caller":"traceutil/trace.go:171","msg":"trace[68874673] transaction","detail":"{read_only:false; response_revision:26235; number_of_response:1; }","duration":"137.850651ms","start":"2026-05-05T03:18:56.956033Z","end":"2026-05-05T03:18:57.093884Z","steps":["trace[68874673] 'process raft request' (duration: 128.177449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:57.38721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.540028ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-05T03:18:57.387336Z","caller":"traceutil/trace.go:171","msg":"trace[734445798] linearizableReadLoop","detail":"{readStateIndex:27085; appliedIndex:27084; }","duration":"187.480944ms","start":"2026-05-05T03:18:57.199835Z","end":"2026-05-05T03:18:57.387316Z","steps":["trace[734445798] 'read index received' (duration: 31.785562ms)","trace[734445798] 'applied index is now lower than readState.Index' (duration: 155.693872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:18:57.387791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.943787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T03:18:57.387837Z","caller":"traceutil/trace.go:171","msg":"trace[1294291961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26235; }","duration":"188.04086ms","start":"2026-05-05T03:18:57.199783Z","end":"2026-05-05T03:18:57.387824Z","steps":["trace[1294291961] 'agreement among raft nodes before linearized reading' (duration: 187.842245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:57.699085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.301804ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T03:18:57.699259Z","caller":"traceutil/trace.go:171","msg":"trace[68445865] linearizableReadLoop","detail":"{readStateIndex:27087; appliedIndex:27085; }","duration":"303.431669ms","start":"2026-05-05T03:18:57.395812Z","end":"2026-05-05T03:18:57.699244Z","steps":["trace[68445865] 'read index received' (duration: 123.95221ms)","trace[68445865] 'applied index is now lower than readState.Index' (duration: 179.478329ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:57.699318Z","caller":"traceutil/trace.go:171","msg":"trace[1316251886] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"309.15812ms","start":"2026-05-05T03:18:57.390151Z","end":"2026-05-05T03:18:57.69931Z","steps":["trace[1316251886] 'process raft request' (duration: 129.570008ms)","trace[1316251886] 'compare' (duration: 178.465411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:18:57.699355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:57.390118Z","time spent":"309.216222ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:18:57.699533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.679337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:18:57.699623Z","caller":"traceutil/trace.go:171","msg":"trace[925336451] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26237; }","duration":"303.81999ms","start":"2026-05-05T03:18:57.39578Z","end":"2026-05-05T03:18:57.6996Z","steps":["trace[925336451] 'agreement among raft nodes before linearized reading' (duration: 303.576014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:57.699663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:57.395773Z","time spent":"303.883333ms","remote":"127.0.0.1:53456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true "} {"level":"info","ts":"2026-05-05T03:18:57.699856Z","caller":"traceutil/trace.go:171","msg":"trace[878722089] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"306.114295ms","start":"2026-05-05T03:18:57.393733Z","end":"2026-05-05T03:18:57.699848Z","steps":["trace[878722089] 'process raft request' (duration: 305.428446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:57.699904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:57.393703Z","time spent":"306.177617ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:58.007973Z","caller":"traceutil/trace.go:171","msg":"trace[105894508] linearizableReadLoop","detail":"{readStateIndex:27088; appliedIndex:27087; }","duration":"113.170517ms","start":"2026-05-05T03:18:57.894767Z","end":"2026-05-05T03:18:58.007938Z","steps":["trace[105894508] 'read index received' (duration: 112.861819ms)","trace[105894508] 'applied index is now lower than readState.Index' (duration: 307.478µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:58.008099Z","caller":"traceutil/trace.go:171","msg":"trace[20371243] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"130.914225ms","start":"2026-05-05T03:18:57.877132Z","end":"2026-05-05T03:18:58.008046Z","steps":["trace[20371243] 'process raft request' (duration: 130.577526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:58.008236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.439305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T03:18:58.00829Z","caller":"traceutil/trace.go:171","msg":"trace[2130771976] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26238; }","duration":"113.57345ms","start":"2026-05-05T03:18:57.894701Z","end":"2026-05-05T03:18:58.008274Z","steps":["trace[2130771976] 'agreement among raft nodes before linearized reading' (duration: 113.365363ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:58.312596Z","caller":"traceutil/trace.go:171","msg":"trace[620696142] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"106.874501ms","start":"2026-05-05T03:18:58.205692Z","end":"2026-05-05T03:18:58.312567Z","steps":["trace[620696142] 'process raft request' (duration: 106.620194ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:58.557744Z","caller":"traceutil/trace.go:171","msg":"trace[292566291] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"350.974176ms","start":"2026-05-05T03:18:58.206734Z","end":"2026-05-05T03:18:58.557708Z","steps":["trace[292566291] 'process raft request' (duration: 350.702158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:58.557969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:58.206683Z","time spent":"351.168501ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:58.55819Z","caller":"traceutil/trace.go:171","msg":"trace[2099728340] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"351.367696ms","start":"2026-05-05T03:18:58.206787Z","end":"2026-05-05T03:18:58.558154Z","steps":["trace[2099728340] 'process raft request' (duration: 350.854832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:58.558464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:18:58.206761Z","time spent":"351.554342ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:18:58.558571Z","caller":"traceutil/trace.go:171","msg":"trace[2053417719] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"270.764393ms","start":"2026-05-05T03:18:58.287744Z","end":"2026-05-05T03:18:58.558509Z","steps":["trace[2053417719] 'process raft request' (duration: 270.370532ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:58.558632Z","caller":"traceutil/trace.go:171","msg":"trace[386497456] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"270.499856ms","start":"2026-05-05T03:18:58.288082Z","end":"2026-05-05T03:18:58.558582Z","steps":["trace[386497456] 'process raft request' (duration: 270.2869ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:58.558694Z","caller":"traceutil/trace.go:171","msg":"trace[400042228] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"270.436834ms","start":"2026-05-05T03:18:58.28823Z","end":"2026-05-05T03:18:58.558667Z","steps":["trace[400042228] 'process raft request' (duration: 270.238648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:58.558934Z","caller":"traceutil/trace.go:171","msg":"trace[1785934522] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"270.287789ms","start":"2026-05-05T03:18:58.288633Z","end":"2026-05-05T03:18:58.558921Z","steps":["trace[1785934522] 'process raft request' (duration: 269.994141ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:58.558968Z","caller":"traceutil/trace.go:171","msg":"trace[357337881] linearizableReadLoop","detail":"{readStateIndex:27097; appliedIndex:27090; }","duration":"248.841187ms","start":"2026-05-05T03:18:58.310096Z","end":"2026-05-05T03:18:58.558937Z","steps":["trace[357337881] 'read index received' (duration: 2.526061ms)","trace[357337881] 'applied index is now lower than readState.Index' (duration: 246.296896ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:18:58.559002Z","caller":"traceutil/trace.go:171","msg":"trace[913627217] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"169.369596ms","start":"2026-05-05T03:18:58.389516Z","end":"2026-05-05T03:18:58.558885Z","steps":["trace[913627217] 'process raft request' (duration: 169.217812ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:18:58.559017Z","caller":"traceutil/trace.go:171","msg":"trace[683109769] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"270.637118ms","start":"2026-05-05T03:18:58.288335Z","end":"2026-05-05T03:18:58.558972Z","steps":["trace[683109769] 'process raft request' (duration: 270.217366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:58.559157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.265269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:18:58.559242Z","caller":"traceutil/trace.go:171","msg":"trace[610654545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26248; }","duration":"114.352371ms","start":"2026-05-05T03:18:58.444877Z","end":"2026-05-05T03:18:58.55923Z","steps":["trace[610654545] 'agreement among raft nodes before linearized reading' (duration: 114.236538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:58.559259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.162427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T03:18:58.55931Z","caller":"traceutil/trace.go:171","msg":"trace[852432180] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:26248; }","duration":"249.217888ms","start":"2026-05-05T03:18:58.310078Z","end":"2026-05-05T03:18:58.559296Z","steps":["trace[852432180] 'agreement among raft nodes before linearized reading' (duration: 248.967341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:58.559905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.878154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T03:18:58.55996Z","caller":"traceutil/trace.go:171","msg":"trace[100681848] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:26248; }","duration":"135.943626ms","start":"2026-05-05T03:18:58.424005Z","end":"2026-05-05T03:18:58.559948Z","steps":["trace[100681848] 'agreement among raft nodes before linearized reading' (duration: 135.654708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:18:58.561449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.616398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T03:18:58.561543Z","caller":"traceutil/trace.go:171","msg":"trace[22710884] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26248; }","duration":"171.749063ms","start":"2026-05-05T03:18:58.389769Z","end":"2026-05-05T03:18:58.561518Z","steps":["trace[22710884] 'agreement among raft nodes before linearized reading' (duration: 169.330655ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:03.971892Z","caller":"traceutil/trace.go:171","msg":"trace[866886351] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"240.676119ms","start":"2026-05-05T03:19:03.73119Z","end":"2026-05-05T03:19:03.971866Z","steps":["trace[866886351] 'process raft request' (duration: 240.457393ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:03.9725Z","caller":"traceutil/trace.go:171","msg":"trace[2123661290] transaction","detail":"{read_only:false; response_revision:26280; number_of_response:1; }","duration":"134.536338ms","start":"2026-05-05T03:19:03.837947Z","end":"2026-05-05T03:19:03.972483Z","steps":["trace[2123661290] 'process raft request' (duration: 134.456776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:04.335183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.871793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T03:19:04.335381Z","caller":"traceutil/trace.go:171","msg":"trace[1525718618] linearizableReadLoop","detail":"{readStateIndex:27132; appliedIndex:27131; }","duration":"212.882827ms","start":"2026-05-05T03:19:04.122479Z","end":"2026-05-05T03:19:04.335362Z","steps":["trace[1525718618] 'read index received' (duration: 61.402µs)","trace[1525718618] 'applied index is now lower than readState.Index' (duration: 212.819265ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:04.335668Z","caller":"traceutil/trace.go:171","msg":"trace[1009613808] transaction","detail":"{read_only:false; response_revision:26281; number_of_response:1; }","duration":"306.802524ms","start":"2026-05-05T03:19:04.028824Z","end":"2026-05-05T03:19:04.335626Z","steps":["trace[1009613808] 'process raft request' (duration: 34.384765ms)","trace[1009613808] 'compare' (duration: 271.263977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:04.335742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.264938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T03:19:04.335828Z","caller":"traceutil/trace.go:171","msg":"trace[488337400] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26281; }","duration":"213.413953ms","start":"2026-05-05T03:19:04.122396Z","end":"2026-05-05T03:19:04.33581Z","steps":["trace[488337400] 'agreement among raft nodes before linearized reading' (duration: 213.215497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:04.335881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.028793Z","time spent":"306.971378ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:04.889822Z","caller":"traceutil/trace.go:171","msg":"trace[629581383] transaction","detail":"{read_only:false; response_revision:26282; number_of_response:1; }","duration":"552.971967ms","start":"2026-05-05T03:19:04.336813Z","end":"2026-05-05T03:19:04.889785Z","steps":["trace[629581383] 'process raft request' (duration: 552.71653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:04.890185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.33679Z","time spent":"553.140781ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:05.197624Z","caller":"traceutil/trace.go:171","msg":"trace[109165144] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"609.547406ms","start":"2026-05-05T03:19:04.588022Z","end":"2026-05-05T03:19:05.19757Z","steps":["trace[109165144] 'process raft request' (duration: 609.488694ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:05.197636Z","caller":"traceutil/trace.go:171","msg":"trace[1497011453] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"856.934242ms","start":"2026-05-05T03:19:04.340636Z","end":"2026-05-05T03:19:05.19757Z","steps":["trace[1497011453] 'process raft request' (duration: 856.685995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.197786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.587982Z","time spent":"609.71218ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:19:05.19784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.340612Z","time spent":"857.116117ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:05.19805Z","caller":"traceutil/trace.go:171","msg":"trace[550378090] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"608.137045ms","start":"2026-05-05T03:19:04.589892Z","end":"2026-05-05T03:19:05.198029Z","steps":["trace[550378090] 'process raft request' (duration: 607.649092ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:05.198116Z","caller":"traceutil/trace.go:171","msg":"trace[1092032626] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"606.633374ms","start":"2026-05-05T03:19:04.591472Z","end":"2026-05-05T03:19:05.198106Z","steps":["trace[1092032626] 'process raft request' (duration: 606.451519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.198149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.589852Z","time spent":"608.248278ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:05.198132Z","caller":"traceutil/trace.go:171","msg":"trace[1771958385] linearizableReadLoop","detail":"{readStateIndex:27134; appliedIndex:27132; }","duration":"753.387685ms","start":"2026-05-05T03:19:04.444724Z","end":"2026-05-05T03:19:05.198112Z","steps":["trace[1771958385] 'read index received' (duration: 444.916783ms)","trace[1771958385] 'applied index is now lower than readState.Index' (duration: 308.470092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:05.198178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.591456Z","time spent":"606.695125ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:05.198224Z","caller":"traceutil/trace.go:171","msg":"trace[879278956] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"345.637145ms","start":"2026-05-05T03:19:04.852576Z","end":"2026-05-05T03:19:05.198213Z","steps":["trace[879278956] 'process raft request' (duration: 345.497061ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:05.198198Z","caller":"traceutil/trace.go:171","msg":"trace[135670160] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"598.602899ms","start":"2026-05-05T03:19:04.599546Z","end":"2026-05-05T03:19:05.198149Z","steps":["trace[135670160] 'process raft request' (duration: 598.487306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.198311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.58651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T03:19:05.198338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.852558Z","time spent":"345.687216ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:05.198351Z","caller":"traceutil/trace.go:171","msg":"trace[196636003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26288; }","duration":"753.649942ms","start":"2026-05-05T03:19:04.444688Z","end":"2026-05-05T03:19:05.198338Z","steps":["trace[196636003] 'agreement among raft nodes before linearized reading' (duration: 753.544429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.198381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.444672Z","time spent":"753.702683ms","remote":"127.0.0.1:34544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T03:19:05.198413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.599529Z","time spent":"598.763843ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:05.277882Z","caller":"traceutil/trace.go:171","msg":"trace[1785411530] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"387.184672ms","start":"2026-05-05T03:19:04.890666Z","end":"2026-05-05T03:19:05.277851Z","steps":["trace[1785411530] 'process raft request' (duration: 386.556554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.278046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.890651Z","time spent":"387.304986ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:19:05.278319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.177567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T03:19:05.278468Z","caller":"traceutil/trace.go:171","msg":"trace[184040286] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:26289; }","duration":"473.362322ms","start":"2026-05-05T03:19:04.805087Z","end":"2026-05-05T03:19:05.27845Z","steps":["trace[184040286] 'agreement among raft nodes before linearized reading' (duration: 473.152706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.278508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.805061Z","time spent":"473.434643ms","remote":"127.0.0.1:50228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":29,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-05-05T03:19:05.278804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.295778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-05T03:19:05.2788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.195596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T03:19:05.278841Z","caller":"traceutil/trace.go:171","msg":"trace[1833715359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26289; }","duration":"682.388351ms","start":"2026-05-05T03:19:04.596443Z","end":"2026-05-05T03:19:05.278831Z","steps":["trace[1833715359] 'agreement among raft nodes before linearized reading' (duration: 682.254038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.278874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.596418Z","time spent":"682.448762ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T03:19:05.278877Z","caller":"traceutil/trace.go:171","msg":"trace[656121659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26289; }","duration":"682.3615ms","start":"2026-05-05T03:19:04.596498Z","end":"2026-05-05T03:19:05.278859Z","steps":["trace[656121659] 'agreement among raft nodes before linearized reading' (duration: 682.123284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.278922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.596477Z","time spent":"682.437083ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T03:19:05.278936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.919036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T03:19:05.279069Z","caller":"traceutil/trace.go:171","msg":"trace[885615721] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:26289; }","duration":"636.157962ms","start":"2026-05-05T03:19:04.642883Z","end":"2026-05-05T03:19:05.279041Z","steps":["trace[885615721] 'agreement among raft nodes before linearized reading' (duration: 635.844134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.2791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.114987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-05-05T03:19:05.279123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.642854Z","time spent":"636.255395ms","remote":"127.0.0.1:35112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-05T03:19:05.27918Z","caller":"traceutil/trace.go:171","msg":"trace[1632400708] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26289; }","duration":"680.22129ms","start":"2026-05-05T03:19:04.598934Z","end":"2026-05-05T03:19:05.279155Z","steps":["trace[1632400708] 'agreement among raft nodes before linearized reading' (duration: 679.982994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.279241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.598924Z","time spent":"680.300422ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T03:19:05.279247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.088214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T03:19:05.279349Z","caller":"traceutil/trace.go:171","msg":"trace[1263202140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26289; }","duration":"681.230568ms","start":"2026-05-05T03:19:04.598092Z","end":"2026-05-05T03:19:05.279323Z","steps":["trace[1263202140] 'agreement among raft nodes before linearized reading' (duration: 680.861528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.279418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.598079Z","time spent":"681.3295ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T03:19:05.279759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.450348ms","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-05-05T03:19:05.27982Z","caller":"traceutil/trace.go:171","msg":"trace[764002464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26289; }","duration":"679.568661ms","start":"2026-05-05T03:19:04.600237Z","end":"2026-05-05T03:19:05.279805Z","steps":["trace[764002464] 'agreement among raft nodes before linearized reading' (duration: 679.304514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:05.279854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:04.60022Z","time spent":"679.626633ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T03:19:09.337704Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a4e.wal"} {"level":"info","ts":"2026-05-05T03:19:10.589933Z","caller":"traceutil/trace.go:171","msg":"trace[1084264427] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"233.082545ms","start":"2026-05-05T03:19:10.356807Z","end":"2026-05-05T03:19:10.589889Z","steps":["trace[1084264427] 'process raft request' (duration: 174.009846ms)","trace[1084264427] 'compare' (duration: 58.777851ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:11.10929Z","caller":"traceutil/trace.go:171","msg":"trace[2105018011] linearizableReadLoop","detail":"{readStateIndex:27233; appliedIndex:27232; }","duration":"200.84019ms","start":"2026-05-05T03:19:10.908422Z","end":"2026-05-05T03:19:11.109262Z","steps":["trace[2105018011] 'read index received' (duration: 200.543722ms)","trace[2105018011] 'applied index is now lower than readState.Index' (duration: 295.658µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:11.109543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.099877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be80042a-53fc-4964-8ce1-b2f77881917d\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-05T03:19:11.109591Z","caller":"traceutil/trace.go:171","msg":"trace[1903591799] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be80042a-53fc-4964-8ce1-b2f77881917d; range_end:; response_count:1; response_revision:26381; }","duration":"201.233741ms","start":"2026-05-05T03:19:10.90834Z","end":"2026-05-05T03:19:11.109574Z","steps":["trace[1903591799] 'agreement among raft nodes before linearized reading' (duration: 201.052016ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.10958Z","caller":"traceutil/trace.go:171","msg":"trace[1548587262] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"341.140859ms","start":"2026-05-05T03:19:10.768381Z","end":"2026-05-05T03:19:11.109522Z","steps":["trace[1548587262] 'process raft request' (duration: 340.694297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:11.109816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:10.768359Z","time spent":"341.323144ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:11.16034Z","caller":"traceutil/trace.go:171","msg":"trace[50505665] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"246.644746ms","start":"2026-05-05T03:19:10.91365Z","end":"2026-05-05T03:19:11.160295Z","steps":["trace[50505665] 'process raft request' (duration: 246.362238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:11.16038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.516139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-05T03:19:11.160417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.635899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:11.160566Z","caller":"traceutil/trace.go:171","msg":"trace[1685943231] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:26382; }","duration":"119.863565ms","start":"2026-05-05T03:19:11.040687Z","end":"2026-05-05T03:19:11.16055Z","steps":["trace[1685943231] 'agreement among raft nodes before linearized reading' (duration: 119.625138ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.160572Z","caller":"traceutil/trace.go:171","msg":"trace[1915696722] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26382; }","duration":"242.779106ms","start":"2026-05-05T03:19:10.917762Z","end":"2026-05-05T03:19:11.160541Z","steps":["trace[1915696722] 'agreement among raft nodes before linearized reading' (duration: 242.510389ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.324618Z","caller":"traceutil/trace.go:171","msg":"trace[1522277379] transaction","detail":"{read_only:false; response_revision:26383; number_of_response:1; }","duration":"207.806145ms","start":"2026-05-05T03:19:11.11679Z","end":"2026-05-05T03:19:11.324596Z","steps":["trace[1522277379] 'process raft request' (duration: 207.607279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.478008Z","caller":"traceutil/trace.go:171","msg":"trace[857676465] transaction","detail":"{read_only:false; response_revision:26384; number_of_response:1; }","duration":"251.043979ms","start":"2026-05-05T03:19:11.226946Z","end":"2026-05-05T03:19:11.47799Z","steps":["trace[857676465] 'process raft request' (duration: 250.879404ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.478179Z","caller":"traceutil/trace.go:171","msg":"trace[2048546749] transaction","detail":"{read_only:false; response_revision:26385; number_of_response:1; }","duration":"248.343604ms","start":"2026-05-05T03:19:11.229823Z","end":"2026-05-05T03:19:11.478167Z","steps":["trace[2048546749] 'process raft request' (duration: 248.131578ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.478278Z","caller":"traceutil/trace.go:171","msg":"trace[1557954745] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"170.108067ms","start":"2026-05-05T03:19:11.308161Z","end":"2026-05-05T03:19:11.478269Z","steps":["trace[1557954745] 'process raft request' (duration: 170.021384ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.478258Z","caller":"traceutil/trace.go:171","msg":"trace[551260727] linearizableReadLoop","detail":"{readStateIndex:27238; appliedIndex:27234; }","duration":"235.700938ms","start":"2026-05-05T03:19:11.242538Z","end":"2026-05-05T03:19:11.478239Z","steps":["trace[551260727] 'read index received' (duration: 81.950061ms)","trace[551260727] 'applied index is now lower than readState.Index' (duration: 153.749857ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:11.478304Z","caller":"traceutil/trace.go:171","msg":"trace[1708720235] transaction","detail":"{read_only:false; response_revision:26386; number_of_response:1; }","duration":"248.298953ms","start":"2026-05-05T03:19:11.229969Z","end":"2026-05-05T03:19:11.478268Z","steps":["trace[1708720235] 'process raft request' (duration: 248.123258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:11.478471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.879849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be80042a-53fc-4964-8ce1-b2f77881917d\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-05-05T03:19:11.478479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.885884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-05T03:19:11.478504Z","caller":"traceutil/trace.go:171","msg":"trace[2063292265] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be80042a-53fc-4964-8ce1-b2f77881917d; range_end:; response_count:1; response_revision:26387; }","duration":"150.021863ms","start":"2026-05-05T03:19:11.328473Z","end":"2026-05-05T03:19:11.478494Z","steps":["trace[2063292265] 'agreement among raft nodes before linearized reading' (duration: 149.90046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:11.478532Z","caller":"traceutil/trace.go:171","msg":"trace[1726358160] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26387; }","duration":"236.010406ms","start":"2026-05-05T03:19:11.242504Z","end":"2026-05-05T03:19:11.478515Z","steps":["trace[1726358160] 'agreement among raft nodes before linearized reading' (duration: 235.79314ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:13.339671Z","caller":"traceutil/trace.go:171","msg":"trace[1298909430] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"173.566894ms","start":"2026-05-05T03:19:13.16607Z","end":"2026-05-05T03:19:13.339637Z","steps":["trace[1298909430] 'process raft request' (duration: 173.274866ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:17.371007Z","caller":"traceutil/trace.go:171","msg":"trace[416930284] transaction","detail":"{read_only:false; response_revision:26445; number_of_response:1; }","duration":"308.259176ms","start":"2026-05-05T03:19:17.062728Z","end":"2026-05-05T03:19:17.370987Z","steps":["trace[416930284] 'process raft request' (duration: 252.565662ms)","trace[416930284] 'compare' (duration: 55.613302ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:17.371114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:17.062694Z","time spent":"308.360529ms","remote":"127.0.0.1:34754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10631,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:17.386534Z","caller":"traceutil/trace.go:171","msg":"trace[373933579] transaction","detail":"{read_only:false; response_revision:26446; number_of_response:1; }","duration":"288.219393ms","start":"2026-05-05T03:19:17.098298Z","end":"2026-05-05T03:19:17.386517Z","steps":["trace[373933579] 'process raft request' (duration: 288.045309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:17.386531Z","caller":"traceutil/trace.go:171","msg":"trace[628931062] linearizableReadLoop","detail":"{readStateIndex:27300; appliedIndex:27298; }","duration":"267.268875ms","start":"2026-05-05T03:19:17.119238Z","end":"2026-05-05T03:19:17.386506Z","steps":["trace[628931062] 'read index received' (duration: 196.072066ms)","trace[628931062] 'applied index is now lower than readState.Index' (duration: 71.195729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:17.38666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.399639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:17.386766Z","caller":"traceutil/trace.go:171","msg":"trace[1163774855] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26446; }","duration":"267.536922ms","start":"2026-05-05T03:19:17.119191Z","end":"2026-05-05T03:19:17.386728Z","steps":["trace[1163774855] 'agreement among raft nodes before linearized reading' (duration: 267.384978ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:17.488943Z","caller":"traceutil/trace.go:171","msg":"trace[1669237240] transaction","detail":"{read_only:false; response_revision:26447; number_of_response:1; }","duration":"136.918754ms","start":"2026-05-05T03:19:17.352004Z","end":"2026-05-05T03:19:17.488923Z","steps":["trace[1669237240] 'process raft request' (duration: 136.109201ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:17.488977Z","caller":"traceutil/trace.go:171","msg":"trace[1736505006] linearizableReadLoop","detail":"{readStateIndex:27305; appliedIndex:27300; }","duration":"102.278332ms","start":"2026-05-05T03:19:17.386683Z","end":"2026-05-05T03:19:17.488961Z","steps":["trace[1736505006] 'read index received' (duration: 101.374697ms)","trace[1736505006] 'applied index is now lower than readState.Index' (duration: 902.805µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:17.489213Z","caller":"traceutil/trace.go:171","msg":"trace[448830371] transaction","detail":"{read_only:false; response_revision:26448; number_of_response:1; }","duration":"111.372487ms","start":"2026-05-05T03:19:17.377828Z","end":"2026-05-05T03:19:17.489201Z","steps":["trace[448830371] 'process raft request' (duration: 110.931064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:17.489436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.934989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-05-05T03:19:17.489461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.743879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-05T03:19:17.489474Z","caller":"traceutil/trace.go:171","msg":"trace[1351768340] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26451; }","duration":"338.005371ms","start":"2026-05-05T03:19:17.151459Z","end":"2026-05-05T03:19:17.489464Z","steps":["trace[1351768340] 'agreement among raft nodes before linearized reading' (duration: 337.885498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:17.489506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:17.151447Z","time spent":"338.051092ms","remote":"127.0.0.1:34730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-05-05T03:19:17.489511Z","caller":"traceutil/trace.go:171","msg":"trace[1194453723] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26451; }","duration":"100.811891ms","start":"2026-05-05T03:19:17.388675Z","end":"2026-05-05T03:19:17.489487Z","steps":["trace[1194453723] 'agreement among raft nodes before linearized reading' (duration: 100.700708ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:17.489729Z","caller":"traceutil/trace.go:171","msg":"trace[764360178] transaction","detail":"{read_only:false; response_revision:26449; number_of_response:1; }","duration":"111.79866ms","start":"2026-05-05T03:19:17.37792Z","end":"2026-05-05T03:19:17.489719Z","steps":["trace[764360178] 'process raft request' (duration: 110.920054ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:17.489835Z","caller":"traceutil/trace.go:171","msg":"trace[2102838260] transaction","detail":"{read_only:false; response_revision:26450; number_of_response:1; }","duration":"111.339287ms","start":"2026-05-05T03:19:17.378488Z","end":"2026-05-05T03:19:17.489828Z","steps":["trace[2102838260] 'process raft request' (duration: 110.397809ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:17.489918Z","caller":"traceutil/trace.go:171","msg":"trace[1807183129] transaction","detail":"{read_only:false; response_revision:26451; number_of_response:1; }","duration":"111.1134ms","start":"2026-05-05T03:19:17.378797Z","end":"2026-05-05T03:19:17.48991Z","steps":["trace[1807183129] 'process raft request' (duration: 110.126322ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:47.745694Z","caller":"traceutil/trace.go:171","msg":"trace[1247616686] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"118.562269ms","start":"2026-05-05T03:19:47.627094Z","end":"2026-05-05T03:19:47.745656Z","steps":["trace[1247616686] 'process raft request' (duration: 118.312762ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:47.74697Z","caller":"traceutil/trace.go:171","msg":"trace[1160622454] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"118.781276ms","start":"2026-05-05T03:19:47.628152Z","end":"2026-05-05T03:19:47.746933Z","steps":["trace[1160622454] 'process raft request' (duration: 118.536779ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:47.747392Z","caller":"traceutil/trace.go:171","msg":"trace[876377383] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"115.06204ms","start":"2026-05-05T03:19:47.632299Z","end":"2026-05-05T03:19:47.747361Z","steps":["trace[876377383] 'process raft request' (duration: 114.591077ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:47.747746Z","caller":"traceutil/trace.go:171","msg":"trace[1519373263] transaction","detail":"{read_only:false; response_revision:26701; number_of_response:1; }","duration":"115.269157ms","start":"2026-05-05T03:19:47.632464Z","end":"2026-05-05T03:19:47.747733Z","steps":["trace[1519373263] 'process raft request' (duration: 115.171334ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:47.747735Z","caller":"traceutil/trace.go:171","msg":"trace[239079789] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"115.320238ms","start":"2026-05-05T03:19:47.632384Z","end":"2026-05-05T03:19:47.747704Z","steps":["trace[239079789] 'process raft request' (duration: 114.877085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:48.565304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.209709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-05T03:19:48.565341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.034225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:48.565426Z","caller":"traceutil/trace.go:171","msg":"trace[1822175907] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26708; }","duration":"196.369464ms","start":"2026-05-05T03:19:48.369006Z","end":"2026-05-05T03:19:48.565375Z","steps":["trace[1822175907] 'count revisions from in-memory index tree' (duration: 196.103017ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:48.565422Z","caller":"traceutil/trace.go:171","msg":"trace[1602793453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26708; }","duration":"123.116937ms","start":"2026-05-05T03:19:48.44228Z","end":"2026-05-05T03:19:48.565397Z","steps":["trace[1602793453] 'range keys from in-memory index tree' (duration: 122.932253ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:50.662332Z","caller":"traceutil/trace.go:171","msg":"trace[1653967199] linearizableReadLoop","detail":"{readStateIndex:27583; appliedIndex:27582; }","duration":"352.108647ms","start":"2026-05-05T03:19:50.310185Z","end":"2026-05-05T03:19:50.662294Z","steps":["trace[1653967199] 'read index received' (duration: 327.791734ms)","trace[1653967199] 'applied index is now lower than readState.Index' (duration: 24.315553ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:50.66254Z","caller":"traceutil/trace.go:171","msg":"trace[1089705544] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"402.124341ms","start":"2026-05-05T03:19:50.260389Z","end":"2026-05-05T03:19:50.662513Z","steps":["trace[1089705544] 'process raft request' (duration: 377.586822ms)","trace[1089705544] 'compare' (duration: 24.167329ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:50.662681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.497407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T03:19:50.662708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:50.260361Z","time spent":"402.234994ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:19:50.662949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.758554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T03:19:50.663223Z","caller":"traceutil/trace.go:171","msg":"trace[1872404700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26722; }","duration":"218.001391ms","start":"2026-05-05T03:19:50.445105Z","end":"2026-05-05T03:19:50.663106Z","steps":["trace[1872404700] 'agreement among raft nodes before linearized reading' (duration: 217.459055ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:50.663356Z","caller":"traceutil/trace.go:171","msg":"trace[850440678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26722; }","duration":"353.185777ms","start":"2026-05-05T03:19:50.310121Z","end":"2026-05-05T03:19:50.663307Z","steps":["trace[850440678] 'agreement among raft nodes before linearized reading' (duration: 352.641551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:50.663582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:50.310092Z","time spent":"353.447045ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T03:19:50.954886Z","caller":"traceutil/trace.go:171","msg":"trace[473109314] linearizableReadLoop","detail":"{readStateIndex:27584; appliedIndex:27583; }","duration":"284.17321ms","start":"2026-05-05T03:19:50.670691Z","end":"2026-05-05T03:19:50.954865Z","steps":["trace[473109314] 'read index received' (duration: 209.925615ms)","trace[473109314] 'applied index is now lower than readState.Index' (duration: 74.246625ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:50.954947Z","caller":"traceutil/trace.go:171","msg":"trace[1512900481] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"285.108296ms","start":"2026-05-05T03:19:50.669775Z","end":"2026-05-05T03:19:50.954883Z","steps":["trace[1512900481] 'process raft request' (duration: 210.662855ms)","trace[1512900481] 'compare' (duration: 74.283336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:50.955094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.383766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-05T03:19:50.955125Z","caller":"traceutil/trace.go:171","msg":"trace[615769463] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:26723; }","duration":"284.458538ms","start":"2026-05-05T03:19:50.670657Z","end":"2026-05-05T03:19:50.955116Z","steps":["trace[615769463] 'agreement among raft nodes before linearized reading' (duration: 284.316864ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:51.209096Z","caller":"traceutil/trace.go:171","msg":"trace[236734232] linearizableReadLoop","detail":"{readStateIndex:27585; appliedIndex:27584; }","duration":"254.113965ms","start":"2026-05-05T03:19:50.954941Z","end":"2026-05-05T03:19:51.209055Z","steps":["trace[236734232] 'read index received' (duration: 252.764088ms)","trace[236734232] 'applied index is now lower than readState.Index' (duration: 1.348297ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:51.209138Z","caller":"traceutil/trace.go:171","msg":"trace[1075938818] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"452.057833ms","start":"2026-05-05T03:19:50.75703Z","end":"2026-05-05T03:19:51.209088Z","steps":["trace[1075938818] 'process raft request' (duration: 450.625393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:51.209378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:50.757007Z","time spent":"452.243768ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:19:51.209471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.364627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:51.209566Z","caller":"traceutil/trace.go:171","msg":"trace[2013603411] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:26724; }","duration":"476.590833ms","start":"2026-05-05T03:19:50.732955Z","end":"2026-05-05T03:19:51.209546Z","steps":["trace[2013603411] 'agreement among raft nodes before linearized reading' (duration: 476.289643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:51.209656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:50.732924Z","time spent":"476.716296ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-05-05T03:19:51.209823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.656899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3b2679ef-643c-4534-906e-471e2ebb3fb9\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-05T03:19:51.20998Z","caller":"traceutil/trace.go:171","msg":"trace[326573619] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3b2679ef-643c-4534-906e-471e2ebb3fb9; range_end:; response_count:1; response_revision:26724; }","duration":"250.861515ms","start":"2026-05-05T03:19:50.959085Z","end":"2026-05-05T03:19:51.209946Z","steps":["trace[326573619] 'agreement among raft nodes before linearized reading' (duration: 250.290049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:52.361114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12855698816056537733,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T03:19:52.905131Z","caller":"traceutil/trace.go:171","msg":"trace[682866607] transaction","detail":"{read_only:false; response_revision:26732; number_of_response:1; }","duration":"1.055033125s","start":"2026-05-05T03:19:51.850064Z","end":"2026-05-05T03:19:52.905097Z","steps":["trace[682866607] 'process raft request' (duration: 1.054782438s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:52.905318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:51.850027Z","time spent":"1.055192869s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:52.905407Z","caller":"traceutil/trace.go:171","msg":"trace[552853133] transaction","detail":"{read_only:false; response_revision:26736; number_of_response:1; }","duration":"1.054243182s","start":"2026-05-05T03:19:51.85115Z","end":"2026-05-05T03:19:52.905393Z","steps":["trace[552853133] 'process raft request' (duration: 1.054196911s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:52.905568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:51.85114Z","time spent":"1.054331924s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:52.905135Z","caller":"traceutil/trace.go:171","msg":"trace[461124528] transaction","detail":"{read_only:false; response_revision:26733; number_of_response:1; }","duration":"1.054933952s","start":"2026-05-05T03:19:51.850175Z","end":"2026-05-05T03:19:52.905109Z","steps":["trace[461124528] 'process raft request' (duration: 1.05484607s)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:52.90555Z","caller":"traceutil/trace.go:171","msg":"trace[287381424] transaction","detail":"{read_only:false; response_revision:26734; number_of_response:1; }","duration":"1.055289662s","start":"2026-05-05T03:19:51.850226Z","end":"2026-05-05T03:19:52.905516Z","steps":["trace[287381424] 'process raft request' (duration: 1.054842619s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:52.905662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:51.850155Z","time spent":"1.055462356s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:19:52.905731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:51.850204Z","time spent":"1.055424466s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T03:19:52.905846Z","caller":"traceutil/trace.go:171","msg":"trace[1955521271] transaction","detail":"{read_only:false; response_revision:26735; number_of_response:1; }","duration":"1.05556454s","start":"2026-05-05T03:19:51.85024Z","end":"2026-05-05T03:19:52.905804Z","steps":["trace[1955521271] 'process raft request' (duration: 1.055049675s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:52.906035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:51.850231Z","time spent":"1.055697073s","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T03:19:53.300612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.339697ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T03:19:53.300849Z","caller":"traceutil/trace.go:171","msg":"trace[113966605] linearizableReadLoop","detail":"{readStateIndex:27598; appliedIndex:27592; }","duration":"1.440439605s","start":"2026-05-05T03:19:51.860389Z","end":"2026-05-05T03:19:53.300829Z","steps":["trace[113966605] 'read index received' (duration: 1.044426816s)","trace[113966605] 'applied index is now lower than readState.Index' (duration: 396.011649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.625621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.665582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:53.62574Z","caller":"traceutil/trace.go:171","msg":"trace[219953243] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26737; }","duration":"524.852967ms","start":"2026-05-05T03:19:53.10086Z","end":"2026-05-05T03:19:53.625713Z","steps":["trace[219953243] 'agreement among raft nodes before linearized reading' (duration: 200.213191ms)","trace[219953243] 'range keys from in-memory index tree' (duration: 324.468262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.625799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:53.100837Z","time spent":"524.949209ms","remote":"127.0.0.1:55844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-05T03:19:53.625768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317422362s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T03:19:53.625888Z","caller":"traceutil/trace.go:171","msg":"trace[1497820875] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26737; }","duration":"1.317646068s","start":"2026-05-05T03:19:52.30821Z","end":"2026-05-05T03:19:53.625856Z","steps":["trace[1497820875] 'agreement among raft nodes before linearized reading' (duration: 993.006152ms)","trace[1497820875] 'range keys from in-memory index tree' (duration: 324.283026ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.62596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:52.30819Z","time spent":"1.317757792s","remote":"127.0.0.1:34754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T03:19:53.626117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.428933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:53.626164Z","caller":"traceutil/trace.go:171","msg":"trace[1524789947] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:26737; }","duration":"786.525085ms","start":"2026-05-05T03:19:52.839626Z","end":"2026-05-05T03:19:53.626151Z","steps":["trace[1524789947] 'agreement among raft nodes before linearized reading' (duration: 461.423257ms)","trace[1524789947] 'count revisions from in-memory index tree' (duration: 325.036897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.626147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.670879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-05T03:19:53.626221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:52.839601Z","time spent":"786.609158ms","remote":"127.0.0.1:39768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"info","ts":"2026-05-05T03:19:53.626251Z","caller":"traceutil/trace.go:171","msg":"trace[698325094] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26737; }","duration":"958.851233ms","start":"2026-05-05T03:19:52.667372Z","end":"2026-05-05T03:19:53.626223Z","steps":["trace[698325094] 'agreement among raft nodes before linearized reading' (duration: 633.820857ms)","trace[698325094] 'count revisions from in-memory index tree' (duration: 324.870962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.626307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:52.667345Z","time spent":"958.950426ms","remote":"127.0.0.1:34834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-05T03:19:53.626581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.748983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-05T03:19:53.626694Z","caller":"traceutil/trace.go:171","msg":"trace[1390042269] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:26737; }","duration":"441.925759ms","start":"2026-05-05T03:19:53.184739Z","end":"2026-05-05T03:19:53.626665Z","steps":["trace[1390042269] 'agreement among raft nodes before linearized reading' (duration: 116.325076ms)","trace[1390042269] 'count revisions from in-memory index tree' (duration: 325.445278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.626754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:53.184727Z","time spent":"442.013161ms","remote":"127.0.0.1:34772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":252,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-05T03:19:53.62684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685895358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:53.626891Z","caller":"traceutil/trace.go:171","msg":"trace[1682751771] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:26737; }","duration":"1.68599215s","start":"2026-05-05T03:19:51.940884Z","end":"2026-05-05T03:19:53.626876Z","steps":["trace[1682751771] 'agreement among raft nodes before linearized reading' (duration: 1.36010042s)","trace[1682751771] 'count revisions from in-memory index tree' (duration: 325.815109ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.626887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254588287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T03:19:53.626913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.766512232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T03:19:53.626932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:51.94086Z","time spent":"1.686062393s","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"info","ts":"2026-05-05T03:19:53.626957Z","caller":"traceutil/trace.go:171","msg":"trace[883003045] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26737; }","duration":"1.766574633s","start":"2026-05-05T03:19:51.860365Z","end":"2026-05-05T03:19:53.62694Z","steps":["trace[883003045] 'agreement among raft nodes before linearized reading' (duration: 1.440553759s)","trace[883003045] 'count revisions from in-memory index tree' (duration: 325.944782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.626993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:51.860351Z","time spent":"1.766631636s","remote":"127.0.0.1:38924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T03:19:53.626955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182626577s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:53.627009Z","caller":"traceutil/trace.go:171","msg":"trace[1775615348] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26737; }","duration":"1.254752922s","start":"2026-05-05T03:19:52.372232Z","end":"2026-05-05T03:19:53.626985Z","steps":["trace[1775615348] 'agreement among raft nodes before linearized reading' (duration: 928.974814ms)","trace[1775615348] 'count revisions from in-memory index tree' (duration: 325.626363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.62705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:52.372217Z","time spent":"1.254823685s","remote":"127.0.0.1:53474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"info","ts":"2026-05-05T03:19:53.627062Z","caller":"traceutil/trace.go:171","msg":"trace[1536129494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26737; }","duration":"1.182799323s","start":"2026-05-05T03:19:52.444235Z","end":"2026-05-05T03:19:53.627034Z","steps":["trace[1536129494] 'agreement among raft nodes before linearized reading' (duration: 856.924082ms)","trace[1536129494] 'range keys from in-memory index tree' (duration: 325.689785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.627117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:52.444213Z","time spent":"1.182893596s","remote":"127.0.0.1:34544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T03:19:53.627029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.979393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T03:19:53.627272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.313207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-05T03:19:53.627315Z","caller":"traceutil/trace.go:171","msg":"trace[1853238547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26737; }","duration":"667.997527ms","start":"2026-05-05T03:19:52.959305Z","end":"2026-05-05T03:19:53.627303Z","steps":["trace[1853238547] 'agreement among raft nodes before linearized reading' (duration: 341.777456ms)","trace[1853238547] 'range keys from in-memory index tree' (duration: 325.464348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.627343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:52.959281Z","time spent":"668.053689ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T03:19:53.627357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102851207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:36 size:299317"} {"level":"info","ts":"2026-05-05T03:19:53.627417Z","caller":"traceutil/trace.go:171","msg":"trace[1322442091] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:36; response_revision:26737; }","duration":"1.102952471s","start":"2026-05-05T03:19:52.524448Z","end":"2026-05-05T03:19:53.6274Z","steps":["trace[1322442091] 'agreement among raft nodes before linearized reading' (duration: 776.762801ms)","trace[1322442091] 'range keys from in-memory index tree' (duration: 325.696715ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:53.627318Z","caller":"traceutil/trace.go:171","msg":"trace[560984297] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26737; }","duration":"560.338504ms","start":"2026-05-05T03:19:53.066921Z","end":"2026-05-05T03:19:53.627259Z","steps":["trace[560984297] 'agreement among raft nodes before linearized reading' (duration: 234.156414ms)","trace[560984297] 'count revisions from in-memory index tree' (duration: 325.802958ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:53.627457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:52.524424Z","time spent":"1.103024712s","remote":"127.0.0.1:35056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":36,"response size":299339,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-05-05T03:19:53.627519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T03:19:53.066902Z","time spent":"560.598731ms","remote":"127.0.0.1:53500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"info","ts":"2026-05-05T03:19:53.760063Z","caller":"traceutil/trace.go:171","msg":"trace[166896855] linearizableReadLoop","detail":"{readStateIndex:27600; appliedIndex:27599; }","duration":"127.797489ms","start":"2026-05-05T03:19:53.632241Z","end":"2026-05-05T03:19:53.760038Z","steps":["trace[166896855] 'read index received' (duration: 127.531692ms)","trace[166896855] 'applied index is now lower than readState.Index' (duration: 264.917µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:53.760553Z","caller":"traceutil/trace.go:171","msg":"trace[894580259] transaction","detail":"{read_only:false; response_revision:26738; number_of_response:1; }","duration":"128.759736ms","start":"2026-05-05T03:19:53.631778Z","end":"2026-05-05T03:19:53.760538Z","steps":["trace[894580259] 'process raft request' (duration: 128.072357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:53.760821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.5534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:53.760872Z","caller":"traceutil/trace.go:171","msg":"trace[1775190673] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26738; }","duration":"128.661783ms","start":"2026-05-05T03:19:53.632195Z","end":"2026-05-05T03:19:53.760857Z","steps":["trace[1775190673] 'agreement among raft nodes before linearized reading' (duration: 128.56328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:53.761152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.686344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T03:19:53.76119Z","caller":"traceutil/trace.go:171","msg":"trace[349243839] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26738; }","duration":"128.747455ms","start":"2026-05-05T03:19:53.632434Z","end":"2026-05-05T03:19:53.761182Z","steps":["trace[349243839] 'agreement among raft nodes before linearized reading' (duration: 128.603311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:53.761582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.432482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:53.761621Z","caller":"traceutil/trace.go:171","msg":"trace[1693454848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26738; }","duration":"126.492623ms","start":"2026-05-05T03:19:53.635119Z","end":"2026-05-05T03:19:53.761612Z","steps":["trace[1693454848] 'agreement among raft nodes before linearized reading' (duration: 126.437191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:54.008641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.606524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T03:19:54.008798Z","caller":"traceutil/trace.go:171","msg":"trace[1642372058] transaction","detail":"{read_only:false; response_revision:26739; number_of_response:1; }","duration":"158.614864ms","start":"2026-05-05T03:19:53.850162Z","end":"2026-05-05T03:19:54.008777Z","steps":["trace[1642372058] 'compare' (duration: 153.424378ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:54.073598Z","caller":"traceutil/trace.go:171","msg":"trace[531734127] linearizableReadLoop","detail":"{readStateIndex:27605; appliedIndex:27601; }","duration":"209.874513ms","start":"2026-05-05T03:19:53.863705Z","end":"2026-05-05T03:19:54.073579Z","steps":["trace[531734127] 'read index received' (duration: 209.562264ms)","trace[531734127] 'applied index is now lower than readState.Index' (duration: 311.589µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:54.074072Z","caller":"traceutil/trace.go:171","msg":"trace[1195776433] transaction","detail":"{read_only:false; response_revision:26743; number_of_response:1; }","duration":"221.977233ms","start":"2026-05-05T03:19:53.852072Z","end":"2026-05-05T03:19:54.07405Z","steps":["trace[1195776433] 'process raft request' (duration: 221.479579ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:54.074166Z","caller":"traceutil/trace.go:171","msg":"trace[1500526957] transaction","detail":"{read_only:false; response_revision:26742; number_of_response:1; }","duration":"222.104136ms","start":"2026-05-05T03:19:53.852022Z","end":"2026-05-05T03:19:54.074126Z","steps":["trace[1500526957] 'process raft request' (duration: 221.50571ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:54.074165Z","caller":"traceutil/trace.go:171","msg":"trace[184063362] transaction","detail":"{read_only:false; response_revision:26741; number_of_response:1; }","duration":"223.65128ms","start":"2026-05-05T03:19:53.85046Z","end":"2026-05-05T03:19:54.074111Z","steps":["trace[184063362] 'process raft request' (duration: 223.037573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:54.0743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.497693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:54.074386Z","caller":"traceutil/trace.go:171","msg":"trace[1450875889] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26743; }","duration":"193.634377ms","start":"2026-05-05T03:19:53.880729Z","end":"2026-05-05T03:19:54.074364Z","steps":["trace[1450875889] 'agreement among raft nodes before linearized reading' (duration: 193.469662ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:54.074404Z","caller":"traceutil/trace.go:171","msg":"trace[1786144780] transaction","detail":"{read_only:false; response_revision:26740; number_of_response:1; }","duration":"224.036421ms","start":"2026-05-05T03:19:53.850358Z","end":"2026-05-05T03:19:54.074394Z","steps":["trace[1786144780] 'process raft request' (duration: 223.010452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:54.074709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.996855ms","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-05-05T03:19:54.074721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.404005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T03:19:54.074743Z","caller":"traceutil/trace.go:171","msg":"trace[514701290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26743; }","duration":"211.070806ms","start":"2026-05-05T03:19:53.863664Z","end":"2026-05-05T03:19:54.074735Z","steps":["trace[514701290] 'agreement among raft nodes before linearized reading' (duration: 210.929192ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:54.074762Z","caller":"traceutil/trace.go:171","msg":"trace[1265805825] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26743; }","duration":"180.463777ms","start":"2026-05-05T03:19:53.894285Z","end":"2026-05-05T03:19:54.074749Z","steps":["trace[1265805825] 'agreement among raft nodes before linearized reading' (duration: 180.390604ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:54.190823Z","caller":"traceutil/trace.go:171","msg":"trace[923924796] linearizableReadLoop","detail":"{readStateIndex:27606; appliedIndex:27605; }","duration":"107.684014ms","start":"2026-05-05T03:19:54.08311Z","end":"2026-05-05T03:19:54.190794Z","steps":["trace[923924796] 'read index received' (duration: 29.388506ms)","trace[923924796] 'applied index is now lower than readState.Index' (duration: 78.294468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:54.191059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.909311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-05T03:19:54.191137Z","caller":"traceutil/trace.go:171","msg":"trace[1518530625] transaction","detail":"{read_only:false; response_revision:26744; number_of_response:1; }","duration":"109.638438ms","start":"2026-05-05T03:19:54.081433Z","end":"2026-05-05T03:19:54.191072Z","steps":["trace[1518530625] 'process raft request' (duration: 31.137574ms)","trace[1518530625] 'compare' (duration: 77.904798ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:54.191236Z","caller":"traceutil/trace.go:171","msg":"trace[912527690] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26744; }","duration":"107.986962ms","start":"2026-05-05T03:19:54.083095Z","end":"2026-05-05T03:19:54.191082Z","steps":["trace[912527690] 'agreement among raft nodes before linearized reading' (duration: 107.795437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:54.552189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.775379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T03:19:54.552262Z","caller":"traceutil/trace.go:171","msg":"trace[1843458662] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:26744; }","duration":"142.928294ms","start":"2026-05-05T03:19:54.409319Z","end":"2026-05-05T03:19:54.552247Z","steps":["trace[1843458662] 'count revisions from in-memory index tree' (duration: 142.661256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:54.55215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.855244ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:54.552358Z","caller":"traceutil/trace.go:171","msg":"trace[381195639] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26744; }","duration":"220.08318ms","start":"2026-05-05T03:19:54.33224Z","end":"2026-05-05T03:19:54.552323Z","steps":["trace[381195639] 'range keys from in-memory index tree' (duration: 219.827143ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:54.756054Z","caller":"traceutil/trace.go:171","msg":"trace[383593952] transaction","detail":"{read_only:false; response_revision:26745; number_of_response:1; }","duration":"270.766284ms","start":"2026-05-05T03:19:54.485255Z","end":"2026-05-05T03:19:54.756021Z","steps":["trace[383593952] 'process raft request' (duration: 270.29403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:54.983587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.792479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:54.983691Z","caller":"traceutil/trace.go:171","msg":"trace[1711007463] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26745; }","duration":"111.961244ms","start":"2026-05-05T03:19:54.871705Z","end":"2026-05-05T03:19:54.983667Z","steps":["trace[1711007463] 'count revisions from in-memory index tree' (duration: 111.672616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:55.422546Z","caller":"traceutil/trace.go:171","msg":"trace[864364104] transaction","detail":"{read_only:false; response_revision:26751; number_of_response:1; }","duration":"116.522991ms","start":"2026-05-05T03:19:55.305991Z","end":"2026-05-05T03:19:55.422514Z","steps":["trace[864364104] 'process raft request' (duration: 116.336716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:55.928214Z","caller":"traceutil/trace.go:171","msg":"trace[1688876004] transaction","detail":"{read_only:false; response_revision:26754; number_of_response:1; }","duration":"157.347578ms","start":"2026-05-05T03:19:55.770837Z","end":"2026-05-05T03:19:55.928184Z","steps":["trace[1688876004] 'process raft request' (duration: 137.004797ms)","trace[1688876004] 'compare' (duration: 20.206507ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:56.29849Z","caller":"traceutil/trace.go:171","msg":"trace[1745630604] transaction","detail":"{read_only:false; response_revision:26755; number_of_response:1; }","duration":"280.218378ms","start":"2026-05-05T03:19:56.018236Z","end":"2026-05-05T03:19:56.298454Z","steps":["trace[1745630604] 'process raft request' (duration: 269.671362ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:56.342696Z","caller":"traceutil/trace.go:171","msg":"trace[407639260] transaction","detail":"{read_only:false; response_revision:26756; number_of_response:1; }","duration":"262.393708ms","start":"2026-05-05T03:19:56.080275Z","end":"2026-05-05T03:19:56.342668Z","steps":["trace[407639260] 'process raft request' (duration: 262.202963ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:56.34314Z","caller":"traceutil/trace.go:171","msg":"trace[261538985] transaction","detail":"{read_only:false; response_revision:26757; number_of_response:1; }","duration":"261.240065ms","start":"2026-05-05T03:19:56.081863Z","end":"2026-05-05T03:19:56.343103Z","steps":["trace[261538985] 'process raft request' (duration: 260.768482ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:56.34334Z","caller":"traceutil/trace.go:171","msg":"trace[1041861255] linearizableReadLoop","detail":"{readStateIndex:27621; appliedIndex:27616; }","duration":"215.491511ms","start":"2026-05-05T03:19:56.127805Z","end":"2026-05-05T03:19:56.343296Z","steps":["trace[1041861255] 'read index received' (duration: 160.119186ms)","trace[1041861255] 'applied index is now lower than readState.Index' (duration: 55.370665ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:56.343393Z","caller":"traceutil/trace.go:171","msg":"trace[2031779637] transaction","detail":"{read_only:false; response_revision:26759; number_of_response:1; }","duration":"261.310907ms","start":"2026-05-05T03:19:56.082065Z","end":"2026-05-05T03:19:56.343376Z","steps":["trace[2031779637] 'process raft request' (duration: 261.173703ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:56.343433Z","caller":"traceutil/trace.go:171","msg":"trace[432677271] transaction","detail":"{read_only:false; response_revision:26758; number_of_response:1; }","duration":"261.40311ms","start":"2026-05-05T03:19:56.081965Z","end":"2026-05-05T03:19:56.343368Z","steps":["trace[432677271] 'process raft request' (duration: 261.06093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:56.343589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.767208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/senlin-api-5c64c7475b-gzxbb.18ac8c771d5104bb\" ","response":"range_response_count:1 size:1170"} {"level":"info","ts":"2026-05-05T03:19:56.343638Z","caller":"traceutil/trace.go:171","msg":"trace[462706946] range","detail":"{range_begin:/registry/events/openstack/senlin-api-5c64c7475b-gzxbb.18ac8c771d5104bb; range_end:; response_count:1; response_revision:26759; }","duration":"215.862401ms","start":"2026-05-05T03:19:56.127761Z","end":"2026-05-05T03:19:56.343624Z","steps":["trace[462706946] 'agreement among raft nodes before linearized reading' (duration: 215.679946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:56.343662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.275757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-05T03:19:56.343739Z","caller":"traceutil/trace.go:171","msg":"trace[99564827] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:26759; }","duration":"120.362169ms","start":"2026-05-05T03:19:56.223354Z","end":"2026-05-05T03:19:56.343717Z","steps":["trace[99564827] 'agreement among raft nodes before linearized reading' (duration: 120.241766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:56.343676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.967096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-05T03:19:56.343811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.173911ms","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-05-05T03:19:56.343849Z","caller":"traceutil/trace.go:171","msg":"trace[695209305] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:26759; }","duration":"148.188091ms","start":"2026-05-05T03:19:56.195636Z","end":"2026-05-05T03:19:56.343824Z","steps":["trace[695209305] 'agreement among raft nodes before linearized reading' (duration: 147.990226ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:56.343896Z","caller":"traceutil/trace.go:171","msg":"trace[1854016362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26759; }","duration":"148.286584ms","start":"2026-05-05T03:19:56.195587Z","end":"2026-05-05T03:19:56.343874Z","steps":["trace[1854016362] 'agreement among raft nodes before linearized reading' (duration: 147.996476ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:56.456268Z","caller":"traceutil/trace.go:171","msg":"trace[684897062] transaction","detail":"{read_only:false; response_revision:26761; number_of_response:1; }","duration":"106.638074ms","start":"2026-05-05T03:19:56.349577Z","end":"2026-05-05T03:19:56.456215Z","steps":["trace[684897062] 'process raft request' (duration: 106.439288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:56.799968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.587056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-05T03:19:56.800133Z","caller":"traceutil/trace.go:171","msg":"trace[1405084760] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"278.380826ms","start":"2026-05-05T03:19:56.521727Z","end":"2026-05-05T03:19:56.800108Z","steps":["trace[1405084760] 'process raft request' (duration: 95.501112ms)","trace[1405084760] 'compare' (duration: 182.430502ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:57.179501Z","caller":"traceutil/trace.go:171","msg":"trace[1117990232] linearizableReadLoop","detail":"{readStateIndex:27626; appliedIndex:27625; }","duration":"112.238481ms","start":"2026-05-05T03:19:57.06723Z","end":"2026-05-05T03:19:57.179468Z","steps":["trace[1117990232] 'read index received' (duration: 91.405946ms)","trace[1117990232] 'applied index is now lower than readState.Index' (duration: 20.828455ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T03:19:57.179855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.623132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-05T03:19:57.179891Z","caller":"traceutil/trace.go:171","msg":"trace[1611168352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26763; }","duration":"112.731016ms","start":"2026-05-05T03:19:57.067151Z","end":"2026-05-05T03:19:57.179883Z","steps":["trace[1611168352] 'agreement among raft nodes before linearized reading' (duration: 112.591552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:57.180042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.124899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T03:19:57.180068Z","caller":"traceutil/trace.go:171","msg":"trace[1277566212] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26763; }","duration":"112.181501ms","start":"2026-05-05T03:19:57.06788Z","end":"2026-05-05T03:19:57.180062Z","steps":["trace[1277566212] 'agreement among raft nodes before linearized reading' (duration: 112.114019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:57.180351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.961764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-05T03:19:57.180377Z","caller":"traceutil/trace.go:171","msg":"trace[255578481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26763; }","duration":"112.005955ms","start":"2026-05-05T03:19:57.068365Z","end":"2026-05-05T03:19:57.180371Z","steps":["trace[255578481] 'agreement among raft nodes before linearized reading' (duration: 111.935133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:57.180488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.457647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-05-05T03:19:57.180533Z","caller":"traceutil/trace.go:171","msg":"trace[1667854194] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26763; }","duration":"112.502329ms","start":"2026-05-05T03:19:57.068004Z","end":"2026-05-05T03:19:57.180506Z","steps":["trace[1667854194] 'agreement among raft nodes before linearized reading' (duration: 112.443447ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:57.327079Z","caller":"traceutil/trace.go:171","msg":"trace[645004981] linearizableReadLoop","detail":"{readStateIndex:27631; appliedIndex:27627; }","duration":"121.09235ms","start":"2026-05-05T03:19:57.205966Z","end":"2026-05-05T03:19:57.327059Z","steps":["trace[645004981] 'read index received' (duration: 102.946191ms)","trace[645004981] 'applied index is now lower than readState.Index' (duration: 18.145309ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:57.327143Z","caller":"traceutil/trace.go:171","msg":"trace[1241579724] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"136.80218ms","start":"2026-05-05T03:19:57.190314Z","end":"2026-05-05T03:19:57.327116Z","steps":["trace[1241579724] 'process raft request' (duration: 136.642576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:57.327233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.252935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-05T03:19:57.327259Z","caller":"traceutil/trace.go:171","msg":"trace[1767819543] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26768; }","duration":"121.316926ms","start":"2026-05-05T03:19:57.205934Z","end":"2026-05-05T03:19:57.327251Z","steps":["trace[1767819543] 'agreement among raft nodes before linearized reading' (duration: 121.200083ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:57.327358Z","caller":"traceutil/trace.go:171","msg":"trace[1724012664] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"137.10179ms","start":"2026-05-05T03:19:57.190237Z","end":"2026-05-05T03:19:57.327339Z","steps":["trace[1724012664] 'process raft request' (duration: 118.753445ms)","trace[1724012664] 'compare' (duration: 17.850751ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T03:19:57.327419Z","caller":"traceutil/trace.go:171","msg":"trace[1483827841] transaction","detail":"{read_only:false; response_revision:26767; number_of_response:1; }","duration":"132.583413ms","start":"2026-05-05T03:19:57.194827Z","end":"2026-05-05T03:19:57.32741Z","steps":["trace[1483827841] 'process raft request' (duration: 132.164501ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:57.327498Z","caller":"traceutil/trace.go:171","msg":"trace[139141450] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"132.499861ms","start":"2026-05-05T03:19:57.194983Z","end":"2026-05-05T03:19:57.327483Z","steps":["trace[139141450] 'process raft request' (duration: 132.034568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T03:19:57.724261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.04275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-05T03:19:57.724598Z","caller":"traceutil/trace.go:171","msg":"trace[219091304] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:26769; }","duration":"110.377079ms","start":"2026-05-05T03:19:57.614117Z","end":"2026-05-05T03:19:57.724494Z","steps":["trace[219091304] 'range keys from in-memory index tree' (duration: 109.809583ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:19:58.03704Z","caller":"traceutil/trace.go:171","msg":"trace[180033783] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"100.620726ms","start":"2026-05-05T03:19:57.936384Z","end":"2026-05-05T03:19:58.037005Z","steps":["trace[180033783] 'process raft request' (duration: 100.311877ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T03:22:43.864664Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25529} {"level":"info","ts":"2026-05-05T03:22:44.019581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25529,"took":"148.574738ms","hash":3650404460,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":35500032,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-05T03:22:44.019645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3650404460,"revision":25529,"compact-revision":22311} {"level":"info","ts":"2026-05-05T03:23:39.762171Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f9f905057b5b268","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T03:23:39.768736Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-05T03:23:39.768896Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-05T03:27:43.873048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28430} {"level":"info","ts":"2026-05-05T03:27:44.011836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28430,"took":"133.412111ms","hash":576122676,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":33673216,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T03:27:44.011928Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":576122676,"revision":28430,"compact-revision":25529} {"level":"info","ts":"2026-05-05T03:32:43.881454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30968} {"level":"info","ts":"2026-05-05T03:32:44.025233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30968,"took":"138.104246ms","hash":3646728686,"current-db-size-bytes":58478592,"current-db-size":"58 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T03:32:44.025346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3646728686,"revision":30968,"compact-revision":28430} {"level":"info","ts":"2026-05-05T03:37:43.888311Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33265} {"level":"info","ts":"2026-05-05T03:37:44.046574Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33265,"took":"150.132711ms","hash":934245505,"current-db-size-bytes":59523072,"current-db-size":"60 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-05T03:37:44.046658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":934245505,"revision":33265,"compact-revision":30968} {"level":"info","ts":"2026-05-05T03:42:43.895122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35579} {"level":"info","ts":"2026-05-05T03:42:44.055218Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35579,"took":"153.836652ms","hash":3327284499,"current-db-size-bytes":60874752,"current-db-size":"61 MB","current-db-size-in-use-bytes":32387072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T03:42:44.055301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3327284499,"revision":35579,"compact-revision":33265} {"level":"info","ts":"2026-05-05T03:44:54.184113Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f9f905057b5b268","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T03:44:54.19028Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-05T03:44:54.190388Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-05T03:47:43.903993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37804} {"level":"info","ts":"2026-05-05T03:47:44.027503Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37804,"took":"115.716514ms","hash":2200160154,"current-db-size-bytes":62570496,"current-db-size":"63 MB","current-db-size-in-use-bytes":33501184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-05T03:47:44.027576Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2200160154,"revision":37804,"compact-revision":35579}