{"level":"warn","ts":"2026-08-04T15:23:31.180692Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:23:31.18082Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-08-04T15:23:31.180886Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-04T15:23:31.180898Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-04T15:23:31.18096Z","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-08-04T15:23:31.181601Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-08-04T15:23:31.181704Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-08-04T15:23:31.186185Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.198704ms"} {"level":"info","ts":"2026-08-04T15:23:31.196685Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-08-04T15:23:31.196773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:23:31.196806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-08-04T15:23:31.196822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:23:31.196839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-08-04T15:23:31.196888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-08-04T15:23:31.213449Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:23:31.221404Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:23:31.22284Z","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-08-04T15:23:31.22499Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:23:31.225375Z","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-08-04T15:23:31.225581Z","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-08-04T15:23:31.2256Z","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-08-04T15:23:31.226044Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:23:31.22675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-08-04T15:23:31.226959Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-08-04T15:23:31.228247Z","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-08-04T15:23:31.228364Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-04T15:23:31.228445Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-08-04T15:23:31.228486Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:23:31.228533Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:23:31.797942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:23:31.798005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:23:31.79803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-08-04T15:23:31.798065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:23:31.798075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-04T15:23:31.798084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-08-04T15:23:31.798094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-08-04T15:23:31.806493Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:23:31.806576Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:23:31.806892Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:23:31.807103Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:23:31.807123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:23:31.807175Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:23:31.810686Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-08-04T15:23:31.811032Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:23:31.81547Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:23:31.815577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:23:31.815622Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:23:44.557548Z","caller":"traceutil/trace.go:171","msg":"trace[424829109] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"281.227406ms","start":"2026-08-04T15:23:44.276284Z","end":"2026-08-04T15:23:44.557512Z","steps":["trace[424829109] 'process raft request' (duration: 281.079292ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:15.790794Z","caller":"traceutil/trace.go:171","msg":"trace[1644059720] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"127.454033ms","start":"2026-08-04T15:24:15.663297Z","end":"2026-08-04T15:24:15.790751Z","steps":["trace[1644059720] 'process raft request' (duration: 127.190375ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:15.98092Z","caller":"traceutil/trace.go:171","msg":"trace[213308233] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"250.420944ms","start":"2026-08-04T15:24:15.730463Z","end":"2026-08-04T15:24:15.980884Z","steps":["trace[213308233] 'process raft request' (duration: 249.784335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:16.443455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.392001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:24:16.443668Z","caller":"traceutil/trace.go:171","msg":"trace[1010643799] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"143.280464ms","start":"2026-08-04T15:24:16.300362Z","end":"2026-08-04T15:24:16.443643Z","steps":["trace[1010643799] 'read index received' (duration: 39.312µs)","trace[1010643799] 'applied index is now lower than readState.Index' (duration: 143.240112ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:16.443685Z","caller":"traceutil/trace.go:171","msg":"trace[1380274127] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"634.396342ms","start":"2026-08-04T15:24:15.809263Z","end":"2026-08-04T15:24:16.44366Z","steps":["trace[1380274127] 'process raft request' (duration: 406.23976ms)","trace[1380274127] 'compare' (duration: 227.239965ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:24:16.443789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:15.809214Z","time spent":"634.530266ms","remote":"127.0.0.1:47206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:24:16.443889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.532341ms","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-08-04T15:24:16.443924Z","caller":"traceutil/trace.go:171","msg":"trace[1219687870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"143.609723ms","start":"2026-08-04T15:24:16.300305Z","end":"2026-08-04T15:24:16.443914Z","steps":["trace[1219687870] 'agreement among raft nodes before linearized reading' (duration: 143.446018ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:16.443917Z","caller":"traceutil/trace.go:171","msg":"trace[1201324354] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"381.934278ms","start":"2026-08-04T15:24:16.061897Z","end":"2026-08-04T15:24:16.443831Z","steps":["trace[1201324354] 'process raft request' (duration: 381.687641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:16.444841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:16.06186Z","time spent":"382.876627ms","remote":"127.0.0.1:47532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:25:49.334768Z","caller":"traceutil/trace.go:171","msg":"trace[1301222909] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"109.683775ms","start":"2026-08-04T15:25:49.225027Z","end":"2026-08-04T15:25:49.33471Z","steps":["trace[1301222909] 'process raft request' (duration: 69.924373ms)","trace[1301222909] 'compare' (duration: 39.447013ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:49.610039Z","caller":"traceutil/trace.go:171","msg":"trace[789484353] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"266.60865ms","start":"2026-08-04T15:25:49.34339Z","end":"2026-08-04T15:25:49.609998Z","steps":["trace[789484353] 'process raft request' (duration: 252.703814ms)","trace[789484353] 'compare' (duration: 13.179854ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:02.103868Z","caller":"traceutil/trace.go:171","msg":"trace[1142746380] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"189.199287ms","start":"2026-08-04T15:26:01.914636Z","end":"2026-08-04T15:26:02.103836Z","steps":["trace[1142746380] 'process raft request' (duration: 189.020492ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:02.182872Z","caller":"traceutil/trace.go:171","msg":"trace[1718023998] linearizableReadLoop","detail":"{readStateIndex:2017; appliedIndex:2015; }","duration":"120.414868ms","start":"2026-08-04T15:26:02.062419Z","end":"2026-08-04T15:26:02.182833Z","steps":["trace[1718023998] 'read index received' (duration: 41.247724ms)","trace[1718023998] 'applied index is now lower than readState.Index' (duration: 79.165564ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:02.183031Z","caller":"traceutil/trace.go:171","msg":"trace[1736304492] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"197.183492ms","start":"2026-08-04T15:26:01.985778Z","end":"2026-08-04T15:26:02.182961Z","steps":["trace[1736304492] 'process raft request' (duration: 158.45475ms)","trace[1736304492] 'compare' (duration: 38.453994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:26:02.183959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.49441ms","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-08-04T15:26:02.184333Z","caller":"traceutil/trace.go:171","msg":"trace[962459672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1945; }","duration":"121.945912ms","start":"2026-08-04T15:26:02.062353Z","end":"2026-08-04T15:26:02.184299Z","steps":["trace[962459672] 'agreement among raft nodes before linearized reading' (duration: 120.654975ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:38.606127Z","caller":"traceutil/trace.go:171","msg":"trace[463183172] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"121.551871ms","start":"2026-08-04T15:26:38.484544Z","end":"2026-08-04T15:26:38.606096Z","steps":["trace[463183172] 'process raft request' (duration: 120.897102ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:44.377489Z","caller":"traceutil/trace.go:171","msg":"trace[1531782830] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"121.518261ms","start":"2026-08-04T15:26:44.255941Z","end":"2026-08-04T15:26:44.377459Z","steps":["trace[1531782830] 'process raft request' (duration: 121.340446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:26:44.960496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.861209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:26:44.960621Z","caller":"traceutil/trace.go:171","msg":"trace[1116173772] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"101.976172ms","start":"2026-08-04T15:26:44.858626Z","end":"2026-08-04T15:26:44.960602Z","steps":["trace[1116173772] 'read index received' (duration: 50.582µs)","trace[1116173772] 'applied index is now lower than readState.Index' (duration: 101.92429ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:26:44.960784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.140876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:26:44.96088Z","caller":"traceutil/trace.go:171","msg":"trace[120675505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2189; }","duration":"102.28579ms","start":"2026-08-04T15:26:44.858573Z","end":"2026-08-04T15:26:44.960859Z","steps":["trace[120675505] 'agreement among raft nodes before linearized reading' (duration: 102.134686ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:26:44.960764Z","caller":"traceutil/trace.go:171","msg":"trace[1566760264] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"260.471968ms","start":"2026-08-04T15:26:44.700225Z","end":"2026-08-04T15:26:44.960697Z","steps":["trace[1566760264] 'process raft request' (duration: 102.29945ms)","trace[1566760264] 'compare' (duration: 157.733156ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:26:45.294966Z","caller":"traceutil/trace.go:171","msg":"trace[2051758991] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"147.847947ms","start":"2026-08-04T15:26:45.147089Z","end":"2026-08-04T15:26:45.294937Z","steps":["trace[2051758991] 'process raft request' (duration: 147.660332ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:02.506703Z","caller":"traceutil/trace.go:171","msg":"trace[1482112784] transaction","detail":"{read_only:false; response_revision:2800; number_of_response:1; }","duration":"185.423515ms","start":"2026-08-04T15:28:02.321254Z","end":"2026-08-04T15:28:02.506678Z","steps":["trace[1482112784] 'process raft request' (duration: 185.208508ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:02.722354Z","caller":"traceutil/trace.go:171","msg":"trace[1327674076] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"351.017998ms","start":"2026-08-04T15:28:02.371276Z","end":"2026-08-04T15:28:02.722294Z","steps":["trace[1327674076] 'process raft request' (duration: 299.738415ms)","trace[1327674076] 'compare' (duration: 50.741767ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:02.722615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:28:02.371237Z","time spent":"351.248504ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:28:03.125874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.409018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:28:03.125969Z","caller":"traceutil/trace.go:171","msg":"trace[267105820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2803; }","duration":"266.569982ms","start":"2026-08-04T15:28:02.859378Z","end":"2026-08-04T15:28:03.125948Z","steps":["trace[267105820] 'range keys from in-memory index tree' (duration: 266.301875ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:03.349997Z","caller":"traceutil/trace.go:171","msg":"trace[1117921209] linearizableReadLoop","detail":"{readStateIndex:2908; appliedIndex:2907; }","duration":"183.763355ms","start":"2026-08-04T15:28:03.166204Z","end":"2026-08-04T15:28:03.349968Z","steps":["trace[1117921209] 'read index received' (duration: 183.496468ms)","trace[1117921209] 'applied index is now lower than readState.Index' (duration: 265.347µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:03.350112Z","caller":"traceutil/trace.go:171","msg":"trace[1917193518] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"192.14071ms","start":"2026-08-04T15:28:03.157929Z","end":"2026-08-04T15:28:03.35007Z","steps":["trace[1917193518] 'process raft request' (duration: 191.79663ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:03.350337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.112585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-04T15:28:03.350432Z","caller":"traceutil/trace.go:171","msg":"trace[1843331151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2804; }","duration":"184.26673ms","start":"2026-08-04T15:28:03.166144Z","end":"2026-08-04T15:28:03.350411Z","steps":["trace[1843331151] 'agreement among raft nodes before linearized reading' (duration: 184.004743ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:13.990381Z","caller":"traceutil/trace.go:171","msg":"trace[373475232] linearizableReadLoop","detail":"{readStateIndex:5124; appliedIndex:5123; }","duration":"134.636418ms","start":"2026-08-04T15:32:13.855626Z","end":"2026-08-04T15:32:13.990263Z","steps":["trace[373475232] 'read index received' (duration: 97.116633ms)","trace[373475232] 'applied index is now lower than readState.Index' (duration: 37.518345ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:13.990638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.002678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:32:13.990688Z","caller":"traceutil/trace.go:171","msg":"trace[535093422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4939; }","duration":"135.120682ms","start":"2026-08-04T15:32:13.855552Z","end":"2026-08-04T15:32:13.990672Z","steps":["trace[535093422] 'agreement among raft nodes before linearized reading' (duration: 134.976308ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:13.990896Z","caller":"traceutil/trace.go:171","msg":"trace[768688754] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"249.370709ms","start":"2026-08-04T15:32:13.741494Z","end":"2026-08-04T15:32:13.990865Z","steps":["trace[768688754] 'process raft request' (duration: 211.37639ms)","trace[768688754] 'compare' (duration: 37.103903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:16.091881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.094786ms","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-08-04T15:32:16.092007Z","caller":"traceutil/trace.go:171","msg":"trace[1237699076] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5051; }","duration":"144.283402ms","start":"2026-08-04T15:32:15.947699Z","end":"2026-08-04T15:32:16.091983Z","steps":["trace[1237699076] 'range keys from in-memory index tree' (duration: 143.999964ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:20.388678Z","caller":"traceutil/trace.go:171","msg":"trace[1748873975] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"113.464324ms","start":"2026-08-04T15:32:20.275174Z","end":"2026-08-04T15:32:20.388638Z","steps":["trace[1748873975] 'process raft request' (duration: 101.227423ms)","trace[1748873975] 'compare' (duration: 11.953992ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:39.418574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.786194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:32:39.41885Z","caller":"traceutil/trace.go:171","msg":"trace[1316312569] transaction","detail":"{read_only:false; response_revision:5507; number_of_response:1; }","duration":"326.519802ms","start":"2026-08-04T15:32:39.092307Z","end":"2026-08-04T15:32:39.418827Z","steps":["trace[1316312569] 'process raft request' (duration: 80.385788ms)","trace[1316312569] 'compare' (duration: 245.516056ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:39.418971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:39.092281Z","time spent":"326.653436ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:39.419113Z","caller":"traceutil/trace.go:171","msg":"trace[200335047] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"326.584104ms","start":"2026-08-04T15:32:39.092507Z","end":"2026-08-04T15:32:39.419091Z","steps":["trace[200335047] 'process raft request' (duration: 326.277965ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:39.419117Z","caller":"traceutil/trace.go:171","msg":"trace[590538358] linearizableReadLoop","detail":"{readStateIndex:5710; appliedIndex:5707; }","duration":"291.694807ms","start":"2026-08-04T15:32:39.127401Z","end":"2026-08-04T15:32:39.419096Z","steps":["trace[590538358] 'read index received' (duration: 45.243103ms)","trace[590538358] 'applied index is now lower than readState.Index' (duration: 246.450414ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:39.419187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:39.0925Z","time spent":"326.654687ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:32:39.41931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.249888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11c5ba2e-8be1-4595-9186-03b9b87794f3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-04T15:32:39.419339Z","caller":"traceutil/trace.go:171","msg":"trace[1129805666] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11c5ba2e-8be1-4595-9186-03b9b87794f3; range_end:; response_count:1; response_revision:5509; }","duration":"168.336391ms","start":"2026-08-04T15:32:39.250996Z","end":"2026-08-04T15:32:39.419332Z","steps":["trace[1129805666] 'agreement among raft nodes before linearized reading' (duration: 168.208197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:39.419513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.094648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:32:39.41961Z","caller":"traceutil/trace.go:171","msg":"trace[42501009] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5509; }","duration":"292.214231ms","start":"2026-08-04T15:32:39.127373Z","end":"2026-08-04T15:32:39.419587Z","steps":["trace[42501009] 'agreement among raft nodes before linearized reading' (duration: 291.790959ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:39.419163Z","caller":"traceutil/trace.go:171","msg":"trace[1847504536] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"326.421679ms","start":"2026-08-04T15:32:39.092687Z","end":"2026-08-04T15:32:39.419109Z","steps":["trace[1847504536] 'process raft request' (duration: 326.337157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:32:39.420491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:32:39.092669Z","time spent":"327.692858ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:32:39.635705Z","caller":"traceutil/trace.go:171","msg":"trace[526984842] linearizableReadLoop","detail":"{readStateIndex:5712; appliedIndex:5711; }","duration":"128.354373ms","start":"2026-08-04T15:32:39.507312Z","end":"2026-08-04T15:32:39.635666Z","steps":["trace[526984842] 'read index received' (duration: 55.770544ms)","trace[526984842] 'applied index is now lower than readState.Index' (duration: 72.582449ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:32:39.63581Z","caller":"traceutil/trace.go:171","msg":"trace[330072717] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"148.530487ms","start":"2026-08-04T15:32:39.487237Z","end":"2026-08-04T15:32:39.635767Z","steps":["trace[330072717] 'process raft request' (duration: 75.894656ms)","trace[330072717] 'compare' (duration: 72.349601ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:32:39.636008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.657001ms","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-08-04T15:32:39.636072Z","caller":"traceutil/trace.go:171","msg":"trace[1595542605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5511; }","duration":"128.794235ms","start":"2026-08-04T15:32:39.507263Z","end":"2026-08-04T15:32:39.636058Z","steps":["trace[1595542605] 'agreement among raft nodes before linearized reading' (duration: 128.538938ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:32:39.850149Z","caller":"traceutil/trace.go:171","msg":"trace[2131287187] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"125.460507ms","start":"2026-08-04T15:32:39.72465Z","end":"2026-08-04T15:32:39.85011Z","steps":["trace[2131287187] 'process raft request' (duration: 125.20491ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:33:31.834136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2963} {"level":"info","ts":"2026-08-04T15:33:31.907581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2963,"took":"71.116032ms","hash":2885531164,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":33505280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-04T15:33:31.907704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2885531164,"revision":2963,"compact-revision":-1} {"level":"info","ts":"2026-08-04T15:38:31.845003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6005} {"level":"info","ts":"2026-08-04T15:38:31.950408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6005,"took":"103.69699ms","hash":3220691140,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":21721088,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-04T15:38:31.950495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3220691140,"revision":6005,"compact-revision":2963} {"level":"info","ts":"2026-08-04T15:38:49.363597Z","caller":"traceutil/trace.go:171","msg":"trace[582425484] linearizableReadLoop","detail":"{readStateIndex:8636; appliedIndex:8635; }","duration":"212.279936ms","start":"2026-08-04T15:38:49.151283Z","end":"2026-08-04T15:38:49.363563Z","steps":["trace[582425484] 'read index received' (duration: 212.067659ms)","trace[582425484] 'applied index is now lower than readState.Index' (duration: 210.577µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:38:49.363754Z","caller":"traceutil/trace.go:171","msg":"trace[1399087511] transaction","detail":"{read_only:false; response_revision:8343; number_of_response:1; }","duration":"273.25685ms","start":"2026-08-04T15:38:49.090485Z","end":"2026-08-04T15:38:49.363742Z","steps":["trace[1399087511] 'process raft request' (duration: 272.849938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:38:49.363938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.623045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cd2zd\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-04T15:38:49.364047Z","caller":"traceutil/trace.go:171","msg":"trace[537429873] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cd2zd; range_end:; response_count:1; response_revision:8343; }","duration":"212.794861ms","start":"2026-08-04T15:38:49.15123Z","end":"2026-08-04T15:38:49.364024Z","steps":["trace[537429873] 'agreement among raft nodes before linearized reading' (duration: 212.512113ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:38:49.506556Z","caller":"traceutil/trace.go:171","msg":"trace[1991155678] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"108.322808ms","start":"2026-08-04T15:38:49.398202Z","end":"2026-08-04T15:38:49.506524Z","steps":["trace[1991155678] 'process raft request' (duration: 108.269946ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:38:49.506736Z","caller":"traceutil/trace.go:171","msg":"trace[1403486733] linearizableReadLoop","detail":"{readStateIndex:8637; appliedIndex:8636; }","duration":"142.981166ms","start":"2026-08-04T15:38:49.3637Z","end":"2026-08-04T15:38:49.506681Z","steps":["trace[1403486733] 'read index received' (duration: 115.293672ms)","trace[1403486733] 'applied index is now lower than readState.Index' (duration: 27.680714ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:38:49.507035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.553375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:38:49.507088Z","caller":"traceutil/trace.go:171","msg":"trace[300906461] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:8345; }","duration":"244.644858ms","start":"2026-08-04T15:38:49.262427Z","end":"2026-08-04T15:38:49.507072Z","steps":["trace[300906461] 'agreement among raft nodes before linearized reading' (duration: 244.498634ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:38:49.507258Z","caller":"traceutil/trace.go:171","msg":"trace[831487914] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"335.950825ms","start":"2026-08-04T15:38:49.171258Z","end":"2026-08-04T15:38:49.507209Z","steps":["trace[831487914] 'process raft request' (duration: 307.742015ms)","trace[831487914] 'compare' (duration: 27.249532ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:38:49.507488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:38:49.171239Z","time spent":"336.14193ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:38:49.507505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.576846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223858"} {"level":"info","ts":"2026-08-04T15:38:49.5076Z","caller":"traceutil/trace.go:171","msg":"trace[1097385430] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8345; }","duration":"138.68582ms","start":"2026-08-04T15:38:49.368895Z","end":"2026-08-04T15:38:49.507581Z","steps":["trace[1097385430] 'agreement among raft nodes before linearized reading' (duration: 138.175205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:01.681619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.978658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:39:01.681746Z","caller":"traceutil/trace.go:171","msg":"trace[830984153] linearizableReadLoop","detail":"{readStateIndex:8703; appliedIndex:8702; }","duration":"108.549934ms","start":"2026-08-04T15:39:01.573176Z","end":"2026-08-04T15:39:01.681726Z","steps":["trace[830984153] 'read index received' (duration: 2.303708ms)","trace[830984153] 'applied index is now lower than readState.Index' (duration: 106.244796ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:39:01.681807Z","caller":"traceutil/trace.go:171","msg":"trace[429143297] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"131.654503ms","start":"2026-08-04T15:39:01.550105Z","end":"2026-08-04T15:39:01.681759Z","steps":["trace[429143297] 'process raft request' (duration: 25.445658ms)","trace[429143297] 'compare' (duration: 105.857575ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:39:01.681927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.7575ms","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-08-04T15:39:01.681959Z","caller":"traceutil/trace.go:171","msg":"trace[2050243298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8407; }","duration":"108.822892ms","start":"2026-08-04T15:39:01.573126Z","end":"2026-08-04T15:39:01.681949Z","steps":["trace[2050243298] 'agreement among raft nodes before linearized reading' (duration: 108.693608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:01.682224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.439895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-04T15:39:01.68235Z","caller":"traceutil/trace.go:171","msg":"trace[2013160443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8407; }","duration":"100.648692ms","start":"2026-08-04T15:39:01.581681Z","end":"2026-08-04T15:39:01.68233Z","steps":["trace[2013160443] 'agreement among raft nodes before linearized reading' (duration: 100.319482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:01.682567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.580169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-04T15:39:01.682632Z","caller":"traceutil/trace.go:171","msg":"trace[680930859] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8407; }","duration":"102.97667ms","start":"2026-08-04T15:39:01.579638Z","end":"2026-08-04T15:39:01.682615Z","steps":["trace[680930859] 'agreement among raft nodes before linearized reading' (duration: 102.28137ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:39:01.993408Z","caller":"traceutil/trace.go:171","msg":"trace[2070866980] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"307.067085ms","start":"2026-08-04T15:39:01.686317Z","end":"2026-08-04T15:39:01.993384Z","steps":["trace[2070866980] 'process raft request' (duration: 302.241272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:01.993546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:39:01.686263Z","time spent":"307.214989ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:39:02.003279Z","caller":"traceutil/trace.go:171","msg":"trace[1315001617] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"316.914614ms","start":"2026-08-04T15:39:01.686348Z","end":"2026-08-04T15:39:02.003262Z","steps":["trace[1315001617] 'process raft request' (duration: 316.778001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:02.003376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:39:01.68632Z","time spent":"317.013908ms","remote":"127.0.0.1:47306","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-08-04T15:39:02.003593Z","caller":"traceutil/trace.go:171","msg":"trace[1347275983] transaction","detail":"{read_only:false; response_revision:8410; number_of_response:1; }","duration":"316.933265ms","start":"2026-08-04T15:39:01.686615Z","end":"2026-08-04T15:39:02.003548Z","steps":["trace[1347275983] 'process raft request' (duration: 316.596495ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:39:02.003745Z","caller":"traceutil/trace.go:171","msg":"trace[1036179252] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"316.247214ms","start":"2026-08-04T15:39:01.687428Z","end":"2026-08-04T15:39:02.003675Z","steps":["trace[1036179252] 'process raft request' (duration: 316.153882ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:39:02.003746Z","caller":"traceutil/trace.go:171","msg":"trace[1525007827] linearizableReadLoop","detail":"{readStateIndex:8705; appliedIndex:8703; }","duration":"317.163572ms","start":"2026-08-04T15:39:01.68651Z","end":"2026-08-04T15:39:02.003674Z","steps":["trace[1525007827] 'read index received' (duration: 302.179941ms)","trace[1525007827] 'applied index is now lower than readState.Index' (duration: 14.982451ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:39:02.003821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:39:01.686606Z","time spent":"317.13149ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:39:02.005509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:39:01.687415Z","time spent":"318.048536ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:39:02.005591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.982884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:39:02.005659Z","caller":"traceutil/trace.go:171","msg":"trace[8286561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8413; }","duration":"147.099957ms","start":"2026-08-04T15:39:01.858545Z","end":"2026-08-04T15:39:02.005645Z","steps":["trace[8286561] 'agreement among raft nodes before linearized reading' (duration: 147.012064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:02.005663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.447757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-c5f456fbc-2xvz2.18c8a451645ce33f\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-08-04T15:39:02.005711Z","caller":"traceutil/trace.go:171","msg":"trace[1911830632] range","detail":"{range_begin:/registry/events/openstack/barbican-api-c5f456fbc-2xvz2.18c8a451645ce33f; range_end:; response_count:1; response_revision:8413; }","duration":"269.517059ms","start":"2026-08-04T15:39:01.73618Z","end":"2026-08-04T15:39:02.005697Z","steps":["trace[1911830632] 'agreement among raft nodes before linearized reading' (duration: 269.337374ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:39:02.005485Z","caller":"traceutil/trace.go:171","msg":"trace[300069623] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"278.424101ms","start":"2026-08-04T15:39:01.727031Z","end":"2026-08-04T15:39:02.005456Z","steps":["trace[300069623] 'process raft request' (duration: 276.598968ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:39:02.005612Z","caller":"traceutil/trace.go:171","msg":"trace[65473509] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"317.002226ms","start":"2026-08-04T15:39:01.686638Z","end":"2026-08-04T15:39:02.00364Z","steps":["trace[65473509] 'process raft request' (duration: 316.816751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:02.006037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:39:01.686615Z","time spent":"319.319934ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:39:02.00643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.906153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223858"} {"level":"info","ts":"2026-08-04T15:39:02.006498Z","caller":"traceutil/trace.go:171","msg":"trace[47270695] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8413; }","duration":"319.996745ms","start":"2026-08-04T15:39:01.686489Z","end":"2026-08-04T15:39:02.006485Z","steps":["trace[47270695] 'agreement among raft nodes before linearized reading' (duration: 318.963314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:02.006534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:39:01.68648Z","time spent":"320.044866ms","remote":"127.0.0.1:47206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":223882,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-04T15:39:43.793671Z","caller":"traceutil/trace.go:171","msg":"trace[1482736086] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"120.133732ms","start":"2026-08-04T15:39:43.673519Z","end":"2026-08-04T15:39:43.793652Z","steps":["trace[1482736086] 'process raft request' (duration: 119.996108ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:39:43.944508Z","caller":"traceutil/trace.go:171","msg":"trace[1315818498] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"146.784045ms","start":"2026-08-04T15:39:43.797691Z","end":"2026-08-04T15:39:43.944475Z","steps":["trace[1315818498] 'process raft request' (duration: 117.331ms)","trace[1315818498] 'compare' (duration: 29.112405ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:39:44.36599Z","caller":"traceutil/trace.go:171","msg":"trace[1067308823] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"171.624618ms","start":"2026-08-04T15:39:44.194342Z","end":"2026-08-04T15:39:44.365967Z","steps":["trace[1067308823] 'process raft request' (duration: 131.152847ms)","trace[1067308823] 'compare' (duration: 40.160671ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:39:44.370477Z","caller":"traceutil/trace.go:171","msg":"trace[1394659813] linearizableReadLoop","detail":"{readStateIndex:9046; appliedIndex:9044; }","duration":"171.271166ms","start":"2026-08-04T15:39:44.199188Z","end":"2026-08-04T15:39:44.370459Z","steps":["trace[1394659813] 'read index received' (duration: 126.501189ms)","trace[1394659813] 'applied index is now lower than readState.Index' (duration: 44.768987ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:39:44.370712Z","caller":"traceutil/trace.go:171","msg":"trace[1650564241] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"175.84409ms","start":"2026-08-04T15:39:44.194859Z","end":"2026-08-04T15:39:44.370703Z","steps":["trace[1650564241] 'process raft request' (duration: 175.523711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:44.370938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.74373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-04T15:39:44.370965Z","caller":"traceutil/trace.go:171","msg":"trace[195854876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8737; }","duration":"171.838272ms","start":"2026-08-04T15:39:44.19912Z","end":"2026-08-04T15:39:44.370959Z","steps":["trace[195854876] 'agreement among raft nodes before linearized reading' (duration: 171.733859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:44.371136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.96253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-08-04T15:39:44.37116Z","caller":"traceutil/trace.go:171","msg":"trace[1920651390] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8737; }","duration":"149.015242ms","start":"2026-08-04T15:39:44.222138Z","end":"2026-08-04T15:39:44.371153Z","steps":["trace[1920651390] 'agreement among raft nodes before linearized reading' (duration: 148.93586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:44.371368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.536639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cd2zd\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-04T15:39:44.371397Z","caller":"traceutil/trace.go:171","msg":"trace[1911757526] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cd2zd; range_end:; response_count:1; response_revision:8737; }","duration":"113.618692ms","start":"2026-08-04T15:39:44.257772Z","end":"2026-08-04T15:39:44.371391Z","steps":["trace[1911757526] 'agreement among raft nodes before linearized reading' (duration: 113.544979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:39:44.371559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.737345ms","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-08-04T15:39:44.371622Z","caller":"traceutil/trace.go:171","msg":"trace[1659600163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8737; }","duration":"145.860759ms","start":"2026-08-04T15:39:44.225744Z","end":"2026-08-04T15:39:44.371605Z","steps":["trace[1659600163] 'agreement among raft nodes before linearized reading' (duration: 145.644973ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:39:58.233183Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023db.wal"} {"level":"info","ts":"2026-08-04T15:41:24.322067Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T15:41:24.327386Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-04T15:41:24.327523Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-04T15:43:31.861202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8099} {"level":"info","ts":"2026-08-04T15:43:31.923805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8099,"took":"60.32528ms","hash":3628980853,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":29134848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-04T15:43:31.923882Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3628980853,"revision":8099,"compact-revision":6005} {"level":"info","ts":"2026-08-04T15:48:31.867785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10636} {"level":"info","ts":"2026-08-04T15:48:31.956797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10636,"took":"85.255966ms","hash":1233971751,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":27369472,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-04T15:48:31.956893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1233971751,"revision":10636,"compact-revision":8099} {"level":"warn","ts":"2026-08-04T15:53:06.939921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.6245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:53:06.940759Z","caller":"traceutil/trace.go:171","msg":"trace[88939491] linearizableReadLoop","detail":"{readStateIndex:15599; appliedIndex:15597; }","duration":"242.663666ms","start":"2026-08-04T15:53:06.698081Z","end":"2026-08-04T15:53:06.940745Z","steps":["trace[88939491] 'read index received' (duration: 104.567973ms)","trace[88939491] 'applied index is now lower than readState.Index' (duration: 138.094933ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:53:06.940779Z","caller":"traceutil/trace.go:171","msg":"trace[2116564990] transaction","detail":"{read_only:false; response_revision:15080; number_of_response:1; }","duration":"281.842113ms","start":"2026-08-04T15:53:06.658903Z","end":"2026-08-04T15:53:06.940745Z","steps":["trace[2116564990] 'process raft request' (duration: 20.309586ms)","trace[2116564990] 'compare' (duration: 260.379623ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:53:06.940844Z","caller":"traceutil/trace.go:171","msg":"trace[1329886055] transaction","detail":"{read_only:false; response_revision:15081; number_of_response:1; }","duration":"280.688108ms","start":"2026-08-04T15:53:06.66012Z","end":"2026-08-04T15:53:06.940808Z","steps":["trace[1329886055] 'process raft request' (duration: 280.546114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:06.941013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.917573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-04T15:53:06.941525Z","caller":"traceutil/trace.go:171","msg":"trace[835890005] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15081; }","duration":"243.467038ms","start":"2026-08-04T15:53:06.69803Z","end":"2026-08-04T15:53:06.941497Z","steps":["trace[835890005] 'agreement among raft nodes before linearized reading' (duration: 242.83082ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:53:07.026737Z","caller":"traceutil/trace.go:171","msg":"trace[1694644473] transaction","detail":"{read_only:false; response_revision:15082; number_of_response:1; }","duration":"178.26903ms","start":"2026-08-04T15:53:06.848442Z","end":"2026-08-04T15:53:07.026711Z","steps":["trace[1694644473] 'process raft request' (duration: 178.134466ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:53:07.096843Z","caller":"traceutil/trace.go:171","msg":"trace[454851097] transaction","detail":"{read_only:false; response_revision:15083; number_of_response:1; }","duration":"189.141898ms","start":"2026-08-04T15:53:06.907666Z","end":"2026-08-04T15:53:07.096808Z","steps":["trace[454851097] 'process raft request' (duration: 160.823229ms)","trace[454851097] 'compare' (duration: 28.060272ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:53:07.096876Z","caller":"traceutil/trace.go:171","msg":"trace[59902171] transaction","detail":"{read_only:false; response_revision:15084; number_of_response:1; }","duration":"151.150446ms","start":"2026-08-04T15:53:06.945685Z","end":"2026-08-04T15:53:07.096836Z","steps":["trace[59902171] 'process raft request' (duration: 151.013252ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:53:07.09765Z","caller":"traceutil/trace.go:171","msg":"trace[238431250] linearizableReadLoop","detail":"{readStateIndex:15601; appliedIndex:15599; }","duration":"156.349818ms","start":"2026-08-04T15:53:06.941267Z","end":"2026-08-04T15:53:07.097617Z","steps":["trace[238431250] 'read index received' (duration: 85.237706ms)","trace[238431250] 'applied index is now lower than readState.Index' (duration: 71.110112ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:53:07.097705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.671717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:53:07.097737Z","caller":"traceutil/trace.go:171","msg":"trace[1097511701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15084; }","duration":"239.751279ms","start":"2026-08-04T15:53:06.857976Z","end":"2026-08-04T15:53:07.097728Z","steps":["trace[1097511701] 'agreement among raft nodes before linearized reading' (duration: 239.689677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:07.097877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.084357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g9zmv\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-04T15:53:07.097932Z","caller":"traceutil/trace.go:171","msg":"trace[463986790] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g9zmv; range_end:; response_count:1; response_revision:15084; }","duration":"271.18087ms","start":"2026-08-04T15:53:06.826736Z","end":"2026-08-04T15:53:07.097917Z","steps":["trace[463986790] 'agreement among raft nodes before linearized reading' (duration: 270.999865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:07.098033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.586234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cd2zd\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-08-04T15:53:07.098063Z","caller":"traceutil/trace.go:171","msg":"trace[778391603] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cd2zd; range_end:; response_count:1; response_revision:15084; }","duration":"338.642055ms","start":"2026-08-04T15:53:06.759413Z","end":"2026-08-04T15:53:07.098055Z","steps":["trace[778391603] 'agreement among raft nodes before linearized reading' (duration: 338.576453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:07.098083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:53:06.759397Z","time spent":"338.680886ms","remote":"127.0.0.1:47206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-cd2zd\" "} {"level":"warn","ts":"2026-08-04T15:53:07.098153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.289889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:53:07.098177Z","caller":"traceutil/trace.go:171","msg":"trace[1709147391] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:15084; }","duration":"332.346621ms","start":"2026-08-04T15:53:06.765822Z","end":"2026-08-04T15:53:07.098168Z","steps":["trace[1709147391] 'agreement among raft nodes before linearized reading' (duration: 332.31119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:07.098194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:53:06.765802Z","time spent":"332.387221ms","remote":"127.0.0.1:33762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:53:07.097899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.344134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g9zmv\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-08-04T15:53:07.098257Z","caller":"traceutil/trace.go:171","msg":"trace[1354379960] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g9zmv; range_end:; response_count:1; response_revision:15084; }","duration":"203.733675ms","start":"2026-08-04T15:53:06.894508Z","end":"2026-08-04T15:53:07.098242Z","steps":["trace[1354379960] 'agreement among raft nodes before linearized reading' (duration: 203.332963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:07.106593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.972859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:53:07.106619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.826368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-l4mxk\" ","response":"range_response_count:1 size:20660"} {"level":"info","ts":"2026-08-04T15:53:07.106645Z","caller":"traceutil/trace.go:171","msg":"trace[443890081] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15085; }","duration":"126.05332ms","start":"2026-08-04T15:53:06.980579Z","end":"2026-08-04T15:53:07.106632Z","steps":["trace[443890081] 'agreement among raft nodes before linearized reading' (duration: 125.963188ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:53:07.106674Z","caller":"traceutil/trace.go:171","msg":"trace[609337730] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-l4mxk; range_end:; response_count:1; response_revision:15085; }","duration":"160.8878ms","start":"2026-08-04T15:53:06.945772Z","end":"2026-08-04T15:53:07.10666Z","steps":["trace[609337730] 'agreement among raft nodes before linearized reading' (duration: 160.721255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:07.106783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.419881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-04T15:53:07.106799Z","caller":"traceutil/trace.go:171","msg":"trace[637785487] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15085; }","duration":"126.475473ms","start":"2026-08-04T15:53:06.980319Z","end":"2026-08-04T15:53:07.106794Z","steps":["trace[637785487] 'agreement among raft nodes before linearized reading' (duration: 126.417321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:53:07.106826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.478594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-04T15:53:07.106847Z","caller":"traceutil/trace.go:171","msg":"trace[432982373] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15085; }","duration":"124.499024ms","start":"2026-08-04T15:53:06.982342Z","end":"2026-08-04T15:53:07.106841Z","steps":["trace[432982373] 'agreement among raft nodes before linearized reading' (duration: 124.422912ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:53:08.246957Z","caller":"traceutil/trace.go:171","msg":"trace[383697686] transaction","detail":"{read_only:false; response_revision:15088; number_of_response:1; }","duration":"129.7956ms","start":"2026-08-04T15:53:08.117131Z","end":"2026-08-04T15:53:08.246926Z","steps":["trace[383697686] 'process raft request' (duration: 129.632145ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:53:31.875172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12803} {"level":"info","ts":"2026-08-04T15:53:31.962532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12803,"took":"84.71108ms","hash":2624647099,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":29745152,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-04T15:53:31.96259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2624647099,"revision":12803,"compact-revision":10636} {"level":"info","ts":"2026-08-04T15:58:31.882477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15211} {"level":"info","ts":"2026-08-04T15:58:31.982224Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15211,"took":"96.084086ms","hash":4132927126,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":31674368,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-04T15:58:31.982342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4132927126,"revision":15211,"compact-revision":12803} {"level":"info","ts":"2026-08-04T15:58:59.751439Z","caller":"traceutil/trace.go:171","msg":"trace[814378101] transaction","detail":"{read_only:false; response_revision:17694; number_of_response:1; }","duration":"139.713152ms","start":"2026-08-04T15:58:59.611693Z","end":"2026-08-04T15:58:59.751406Z","steps":["trace[814378101] 'process raft request' (duration: 139.563156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.199644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.404845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-04T15:59:00.19994Z","caller":"traceutil/trace.go:171","msg":"trace[143004390] transaction","detail":"{read_only:false; response_revision:17699; number_of_response:1; }","duration":"327.707172ms","start":"2026-08-04T15:58:59.872166Z","end":"2026-08-04T15:59:00.199873Z","steps":["trace[143004390] 'process raft request' (duration: 180.985317ms)","trace[143004390] 'compare' (duration: 146.011524ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:59:00.200158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:58:59.872138Z","time spent":"327.920969ms","remote":"127.0.0.1:47098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:59:00.200192Z","caller":"traceutil/trace.go:171","msg":"trace[141875161] transaction","detail":"{read_only:false; response_revision:17702; number_of_response:1; }","duration":"262.252755ms","start":"2026-08-04T15:58:59.937917Z","end":"2026-08-04T15:59:00.20017Z","steps":["trace[141875161] 'process raft request' (duration: 262.192593ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:00.200242Z","caller":"traceutil/trace.go:171","msg":"trace[722621717] linearizableReadLoop","detail":"{readStateIndex:18303; appliedIndex:18300; }","duration":"327.932459ms","start":"2026-08-04T15:58:59.872274Z","end":"2026-08-04T15:59:00.200207Z","steps":["trace[722621717] 'read index received' (duration: 23.845261ms)","trace[722621717] 'applied index is now lower than readState.Index' (duration: 304.082548ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:59:00.200247Z","caller":"traceutil/trace.go:171","msg":"trace[2077608603] transaction","detail":"{read_only:false; response_revision:17701; number_of_response:1; }","duration":"321.147133ms","start":"2026-08-04T15:58:59.879056Z","end":"2026-08-04T15:59:00.200203Z","steps":["trace[2077608603] 'process raft request' (duration: 320.795583ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:00.200283Z","caller":"traceutil/trace.go:171","msg":"trace[1025437393] transaction","detail":"{read_only:false; response_revision:17700; number_of_response:1; }","duration":"328.021072ms","start":"2026-08-04T15:58:59.872219Z","end":"2026-08-04T15:59:00.20024Z","steps":["trace[1025437393] 'process raft request' (duration: 327.533818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.200441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:58:59.879037Z","time spent":"321.302687ms","remote":"127.0.0.1:47206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20878,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:59:00.200434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:58:59.87219Z","time spent":"328.178175ms","remote":"127.0.0.1:47180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":738,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:59:00.200838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.866598ms","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":"info","ts":"2026-08-04T15:59:00.200889Z","caller":"traceutil/trace.go:171","msg":"trace[653770475] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:17702; }","duration":"176.967731ms","start":"2026-08-04T15:59:00.023907Z","end":"2026-08-04T15:59:00.200875Z","steps":["trace[653770475] 'agreement among raft nodes before linearized reading' (duration: 176.870938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.20092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.405929ms","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-08-04T15:59:00.201003Z","caller":"traceutil/trace.go:171","msg":"trace[113018643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17702; }","duration":"222.548953ms","start":"2026-08-04T15:58:59.978434Z","end":"2026-08-04T15:59:00.200983Z","steps":["trace[113018643] 'agreement among raft nodes before linearized reading' (duration: 222.342027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.203018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.72284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128545"} {"level":"info","ts":"2026-08-04T15:59:00.203089Z","caller":"traceutil/trace.go:171","msg":"trace[587335811] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:17702; }","duration":"330.833463ms","start":"2026-08-04T15:58:59.872242Z","end":"2026-08-04T15:59:00.203076Z","steps":["trace[587335811] 'agreement among raft nodes before linearized reading' (duration: 328.233558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.203149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:58:59.872231Z","time spent":"330.908195ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":128569,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-08-04T15:59:00.513709Z","caller":"traceutil/trace.go:171","msg":"trace[645544774] transaction","detail":"{read_only:false; response_revision:17703; number_of_response:1; }","duration":"305.432496ms","start":"2026-08-04T15:59:00.208203Z","end":"2026-08-04T15:59:00.513635Z","steps":["trace[645544774] 'process raft request' (duration: 255.622801ms)","trace[645544774] 'compare' (duration: 49.524627ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:59:00.513963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:00.208176Z","time spent":"305.609891ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:59:00.540552Z","caller":"traceutil/trace.go:171","msg":"trace[718527514] transaction","detail":"{read_only:false; response_revision:17704; number_of_response:1; }","duration":"330.828402ms","start":"2026-08-04T15:59:00.20968Z","end":"2026-08-04T15:59:00.540509Z","steps":["trace[718527514] 'process raft request' (duration: 330.082321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.540776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:00.20964Z","time spent":"331.019728ms","remote":"127.0.0.1:47076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":128529,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:59:00.541011Z","caller":"traceutil/trace.go:171","msg":"trace[1030947272] transaction","detail":"{read_only:false; response_revision:17705; number_of_response:1; }","duration":"296.230668ms","start":"2026-08-04T15:59:00.244765Z","end":"2026-08-04T15:59:00.540996Z","steps":["trace[1030947272] 'process raft request' (duration: 295.221459ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:00.632402Z","caller":"traceutil/trace.go:171","msg":"trace[81112609] transaction","detail":"{read_only:false; response_revision:17707; number_of_response:1; }","duration":"126.019464ms","start":"2026-08-04T15:59:00.506354Z","end":"2026-08-04T15:59:00.632374Z","steps":["trace[81112609] 'process raft request' (duration: 125.970093ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:00.632647Z","caller":"traceutil/trace.go:171","msg":"trace[1555797682] transaction","detail":"{read_only:false; response_revision:17706; number_of_response:1; }","duration":"266.912369ms","start":"2026-08-04T15:59:00.365724Z","end":"2026-08-04T15:59:00.632636Z","steps":["trace[1555797682] 'process raft request' (duration: 266.474156ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:00.632765Z","caller":"traceutil/trace.go:171","msg":"trace[1706663633] linearizableReadLoop","detail":"{readStateIndex:18308; appliedIndex:18305; }","duration":"361.473981ms","start":"2026-08-04T15:59:00.271255Z","end":"2026-08-04T15:59:00.632729Z","steps":["trace[1706663633] 'read index received' (duration: 192.975306ms)","trace[1706663633] 'applied index is now lower than readState.Index' (duration: 168.493245ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:59:00.633039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.758759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-04T15:59:00.633116Z","caller":"traceutil/trace.go:171","msg":"trace[761898208] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:17707; }","duration":"361.911403ms","start":"2026-08-04T15:59:00.27119Z","end":"2026-08-04T15:59:00.633101Z","steps":["trace[761898208] 'agreement among raft nodes before linearized reading' (duration: 361.633795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.63315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:00.271164Z","time spent":"361.977435ms","remote":"127.0.0.1:47114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":179,"response size":33,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:59:00.636951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.557074ms","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-08-04T15:59:00.637048Z","caller":"traceutil/trace.go:171","msg":"trace[1305973746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17708; }","duration":"321.677047ms","start":"2026-08-04T15:59:00.315351Z","end":"2026-08-04T15:59:00.637028Z","steps":["trace[1305973746] 'agreement among raft nodes before linearized reading' (duration: 321.394869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:59:00.637091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:59:00.315281Z","time spent":"321.802891ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:59:00.637342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.781257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-04T15:59:00.6374Z","caller":"traceutil/trace.go:171","msg":"trace[1598815585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17708; }","duration":"204.88111ms","start":"2026-08-04T15:59:00.432511Z","end":"2026-08-04T15:59:00.637392Z","steps":["trace[1598815585] 'agreement among raft nodes before linearized reading' (duration: 204.770307ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:08.155327Z","caller":"traceutil/trace.go:171","msg":"trace[1999920228] transaction","detail":"{read_only:false; response_revision:17830; number_of_response:1; }","duration":"135.87336ms","start":"2026-08-04T15:59:08.018892Z","end":"2026-08-04T15:59:08.154765Z","steps":["trace[1999920228] 'process raft request' (duration: 129.530286ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:08.162752Z","caller":"traceutil/trace.go:171","msg":"trace[1170665322] linearizableReadLoop","detail":"{readStateIndex:18437; appliedIndex:18434; }","duration":"133.217232ms","start":"2026-08-04T15:59:08.029508Z","end":"2026-08-04T15:59:08.162725Z","steps":["trace[1170665322] 'read index received' (duration: 118.930988ms)","trace[1170665322] 'applied index is now lower than readState.Index' (duration: 14.285454ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:59:08.162975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.440818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-04T15:59:08.163008Z","caller":"traceutil/trace.go:171","msg":"trace[216888640] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17832; }","duration":"133.523622ms","start":"2026-08-04T15:59:08.029475Z","end":"2026-08-04T15:59:08.162998Z","steps":["trace[216888640] 'agreement among raft nodes before linearized reading' (duration: 133.338136ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:08.16314Z","caller":"traceutil/trace.go:171","msg":"trace[1199384153] transaction","detail":"{read_only:false; response_revision:17832; number_of_response:1; }","duration":"143.687366ms","start":"2026-08-04T15:59:08.01942Z","end":"2026-08-04T15:59:08.163107Z","steps":["trace[1199384153] 'process raft request' (duration: 143.280474ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:59:08.163048Z","caller":"traceutil/trace.go:171","msg":"trace[1739144600] transaction","detail":"{read_only:false; response_revision:17831; number_of_response:1; }","duration":"144.064747ms","start":"2026-08-04T15:59:08.018955Z","end":"2026-08-04T15:59:08.16302Z","steps":["trace[1739144600] 'process raft request' (duration: 143.639085ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:02:09.315081Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T16:02:09.320734Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-04T16:02:09.320826Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-04T16:03:31.888963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17510} {"level":"info","ts":"2026-08-04T16:03:31.982808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17510,"took":"90.306048ms","hash":2185131559,"current-db-size-bytes":57724928,"current-db-size":"58 MB","current-db-size-in-use-bytes":34836480,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-04T16:03:31.982879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2185131559,"revision":17510,"compact-revision":15211} {"level":"info","ts":"2026-08-04T16:04:25.872499Z","caller":"traceutil/trace.go:171","msg":"trace[1872118286] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"128.681564ms","start":"2026-08-04T16:04:25.743786Z","end":"2026-08-04T16:04:25.872467Z","steps":["trace[1872118286] 'process raft request' (duration: 128.533809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:04:26.17024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.114675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:04:26.17053Z","caller":"traceutil/trace.go:171","msg":"trace[316175871] transaction","detail":"{read_only:false; response_revision:20334; number_of_response:1; }","duration":"399.682111ms","start":"2026-08-04T16:04:25.770815Z","end":"2026-08-04T16:04:26.170497Z","steps":["trace[316175871] 'process raft request' (duration: 399.533237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:04:26.170694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:04:25.7708Z","time spent":"399.801385ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:04:26.170531Z","caller":"traceutil/trace.go:171","msg":"trace[1090749838] transaction","detail":"{read_only:false; response_revision:20333; number_of_response:1; }","duration":"401.05153ms","start":"2026-08-04T16:04:25.769459Z","end":"2026-08-04T16:04:26.17051Z","steps":["trace[1090749838] 'process raft request' (duration: 279.559025ms)","trace[1090749838] 'compare' (duration: 120.96752ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:04:26.170831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:04:25.769432Z","time spent":"401.341859ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:04:26.420161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.685767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:04:26.420298Z","caller":"traceutil/trace.go:171","msg":"trace[1242015707] linearizableReadLoop","detail":"{readStateIndex:21023; appliedIndex:21022; }","duration":"163.961582ms","start":"2026-08-04T16:04:26.256317Z","end":"2026-08-04T16:04:26.420279Z","steps":["trace[1242015707] 'read index received' (duration: 49.023357ms)","trace[1242015707] 'applied index is now lower than readState.Index' (duration: 114.936965ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:04:26.420408Z","caller":"traceutil/trace.go:171","msg":"trace[49131937] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"245.506204ms","start":"2026-08-04T16:04:26.174857Z","end":"2026-08-04T16:04:26.420363Z","steps":["trace[49131937] 'process raft request' (duration: 130.513268ms)","trace[49131937] 'compare' (duration: 114.540643ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:04:26.420479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.162007ms","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-08-04T16:04:26.420764Z","caller":"traceutil/trace.go:171","msg":"trace[1345154115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20335; }","duration":"164.434695ms","start":"2026-08-04T16:04:26.256258Z","end":"2026-08-04T16:04:26.420693Z","steps":["trace[1345154115] 'agreement among raft nodes before linearized reading' (duration: 164.103026ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:04:26.59975Z","caller":"traceutil/trace.go:171","msg":"trace[531032867] transaction","detail":"{read_only:false; response_revision:20337; number_of_response:1; }","duration":"173.970492ms","start":"2026-08-04T16:04:26.425747Z","end":"2026-08-04T16:04:26.599717Z","steps":["trace[531032867] 'process raft request' (duration: 155.297509ms)","trace[531032867] 'compare' (duration: 18.364935ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:04:26.600022Z","caller":"traceutil/trace.go:171","msg":"trace[917269078] transaction","detail":"{read_only:false; response_revision:20338; number_of_response:1; }","duration":"174.20689ms","start":"2026-08-04T16:04:26.425802Z","end":"2026-08-04T16:04:26.600009Z","steps":["trace[917269078] 'process raft request' (duration: 173.834699ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:04:26.600158Z","caller":"traceutil/trace.go:171","msg":"trace[205032667] transaction","detail":"{read_only:false; response_revision:20339; number_of_response:1; }","duration":"173.908161ms","start":"2026-08-04T16:04:26.426166Z","end":"2026-08-04T16:04:26.600074Z","steps":["trace[205032667] 'process raft request' (duration: 173.761466ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:04:26.950221Z","caller":"traceutil/trace.go:171","msg":"trace[1688385004] linearizableReadLoop","detail":"{readStateIndex:21028; appliedIndex:21027; }","duration":"148.682676ms","start":"2026-08-04T16:04:26.801482Z","end":"2026-08-04T16:04:26.950165Z","steps":["trace[1688385004] 'read index received' (duration: 148.421859ms)","trace[1688385004] 'applied index is now lower than readState.Index' (duration: 259.557µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:04:26.950333Z","caller":"traceutil/trace.go:171","msg":"trace[202152641] transaction","detail":"{read_only:false; response_revision:20340; number_of_response:1; }","duration":"290.358749ms","start":"2026-08-04T16:04:26.659944Z","end":"2026-08-04T16:04:26.950302Z","steps":["trace[202152641] 'process raft request' (duration: 290.03777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:04:26.951461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.967055ms","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-08-04T16:04:26.95151Z","caller":"traceutil/trace.go:171","msg":"trace[1314930317] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:20340; }","duration":"150.074948ms","start":"2026-08-04T16:04:26.801423Z","end":"2026-08-04T16:04:26.951498Z","steps":["trace[1314930317] 'agreement among raft nodes before linearized reading' (duration: 148.936604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:08:17.394146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.545618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:08:17.394301Z","caller":"traceutil/trace.go:171","msg":"trace[1680811719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:22792; }","duration":"289.725863ms","start":"2026-08-04T16:08:17.104533Z","end":"2026-08-04T16:08:17.394259Z","steps":["trace[1680811719] 'count revisions from in-memory index tree' (duration: 289.473927ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:08:17.394763Z","caller":"traceutil/trace.go:171","msg":"trace[2126317827] transaction","detail":"{read_only:false; response_revision:22793; number_of_response:1; }","duration":"102.852807ms","start":"2026-08-04T16:08:17.291879Z","end":"2026-08-04T16:08:17.394732Z","steps":["trace[2126317827] 'process raft request' (duration: 88.15582ms)","trace[2126317827] 'compare' (duration: 13.880444ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:08:31.896172Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20048} {"level":"info","ts":"2026-08-04T16:08:32.009611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20048,"took":"109.51049ms","hash":761395649,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":42528768,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-04T16:08:32.009683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":761395649,"revision":20048,"compact-revision":17510} {"level":"warn","ts":"2026-08-04T16:11:52.082351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.145365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:11:52.082573Z","caller":"traceutil/trace.go:171","msg":"trace[128130772] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"189.399062ms","start":"2026-08-04T16:11:51.893154Z","end":"2026-08-04T16:11:52.082553Z","steps":["trace[128130772] 'process raft request' (duration: 72.612409ms)","trace[128130772] 'compare' (duration: 116.044192ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:56.701535Z","caller":"traceutil/trace.go:171","msg":"trace[1538459673] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"188.008103ms","start":"2026-08-04T16:11:56.513504Z","end":"2026-08-04T16:11:56.701512Z","steps":["trace[1538459673] 'process raft request' (duration: 187.417996ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:57.151979Z","caller":"traceutil/trace.go:171","msg":"trace[967132548] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"256.617096ms","start":"2026-08-04T16:11:56.895292Z","end":"2026-08-04T16:11:57.15191Z","steps":["trace[967132548] 'process raft request' (duration: 165.116017ms)","trace[967132548] 'compare' (duration: 91.382476ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:57.20856Z","caller":"traceutil/trace.go:171","msg":"trace[393078836] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"311.049268ms","start":"2026-08-04T16:11:56.897463Z","end":"2026-08-04T16:11:57.208513Z","steps":["trace[393078836] 'process raft request' (duration: 310.531993ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:57.208645Z","caller":"traceutil/trace.go:171","msg":"trace[1995931185] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"205.964655ms","start":"2026-08-04T16:11:57.00265Z","end":"2026-08-04T16:11:57.208614Z","steps":["trace[1995931185] 'process raft request' (duration: 205.726418ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:57.208798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:56.897425Z","time spent":"311.224033ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:11:57.506305Z","caller":"traceutil/trace.go:171","msg":"trace[265171938] linearizableReadLoop","detail":"{readStateIndex:25822; appliedIndex:25819; }","duration":"364.016066ms","start":"2026-08-04T16:11:57.142227Z","end":"2026-08-04T16:11:57.506243Z","steps":["trace[265171938] 'read index received' (duration: 65.778461ms)","trace[265171938] 'applied index is now lower than readState.Index' (duration: 298.236145ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:57.506866Z","caller":"traceutil/trace.go:171","msg":"trace[833192622] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"396.902772ms","start":"2026-08-04T16:11:57.109846Z","end":"2026-08-04T16:11:57.506749Z","steps":["trace[833192622] 'process raft request' (duration: 326.186667ms)","trace[833192622] 'compare' (duration: 69.879881ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:11:57.50697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.696986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-04T16:11:57.507194Z","caller":"traceutil/trace.go:171","msg":"trace[1137764924] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25004; }","duration":"364.986236ms","start":"2026-08-04T16:11:57.142175Z","end":"2026-08-04T16:11:57.507161Z","steps":["trace[1137764924] 'agreement among raft nodes before linearized reading' (duration: 364.577883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:57.507264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.142152Z","time spent":"365.098229ms","remote":"127.0.0.1:47440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":32,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:11:57.507103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.109803Z","time spent":"397.134848ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:11:57.579505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.094651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:11:57.579508Z","caller":"traceutil/trace.go:171","msg":"trace[2034755269] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"267.517922ms","start":"2026-08-04T16:11:57.311962Z","end":"2026-08-04T16:11:57.57948Z","steps":["trace[2034755269] 'process raft request' (duration: 267.326156ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:57.579575Z","caller":"traceutil/trace.go:171","msg":"trace[1926228082] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25005; }","duration":"235.203244ms","start":"2026-08-04T16:11:57.344354Z","end":"2026-08-04T16:11:57.579558Z","steps":["trace[1926228082] 'agreement among raft nodes before linearized reading' (duration: 235.05638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:57.579686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.867868ms","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-08-04T16:11:57.579772Z","caller":"traceutil/trace.go:171","msg":"trace[1129672100] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25005; }","duration":"178.003732ms","start":"2026-08-04T16:11:57.401743Z","end":"2026-08-04T16:11:57.579746Z","steps":["trace[1129672100] 'agreement among raft nodes before linearized reading' (duration: 177.744474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:57.916569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.397095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:11:57.916787Z","caller":"traceutil/trace.go:171","msg":"trace[926783582] linearizableReadLoop","detail":"{readStateIndex:25825; appliedIndex:25823; }","duration":"298.737381ms","start":"2026-08-04T16:11:57.618034Z","end":"2026-08-04T16:11:57.916771Z","steps":["trace[926783582] 'read index received' (duration: 121.120579ms)","trace[926783582] 'applied index is now lower than readState.Index' (duration: 177.615372ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:57.917049Z","caller":"traceutil/trace.go:171","msg":"trace[1490142746] transaction","detail":"{read_only:false; response_revision:25007; number_of_response:1; }","duration":"317.384701ms","start":"2026-08-04T16:11:57.599643Z","end":"2026-08-04T16:11:57.917027Z","steps":["trace[1490142746] 'process raft request' (duration: 317.029031ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:57.917079Z","caller":"traceutil/trace.go:171","msg":"trace[1385535684] transaction","detail":"{read_only:false; response_revision:25006; number_of_response:1; }","duration":"327.334992ms","start":"2026-08-04T16:11:57.58971Z","end":"2026-08-04T16:11:57.917045Z","steps":["trace[1385535684] 'process raft request' (duration: 149.36442ms)","trace[1385535684] 'compare' (duration: 176.983712ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:11:57.917156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.59962Z","time spent":"317.478544ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:11:57.917147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.107381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-04T16:11:57.917196Z","caller":"traceutil/trace.go:171","msg":"trace[1584433900] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25007; }","duration":"299.180613ms","start":"2026-08-04T16:11:57.618002Z","end":"2026-08-04T16:11:57.917182Z","steps":["trace[1584433900] 'agreement among raft nodes before linearized reading' (duration: 299.026508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:57.917233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.589678Z","time spent":"327.471756ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:11:58.27098Z","caller":"traceutil/trace.go:171","msg":"trace[1698240603] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25825; }","duration":"354.133689ms","start":"2026-08-04T16:11:57.916815Z","end":"2026-08-04T16:11:58.270948Z","steps":["trace[1698240603] 'read index received' (duration: 353.740888ms)","trace[1698240603] 'applied index is now lower than readState.Index' (duration: 391.941µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:58.27137Z","caller":"traceutil/trace.go:171","msg":"trace[1714101493] transaction","detail":"{read_only:false; response_revision:25008; number_of_response:1; }","duration":"436.988297ms","start":"2026-08-04T16:11:57.834371Z","end":"2026-08-04T16:11:58.271359Z","steps":["trace[1714101493] 'process raft request' (duration: 436.293017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.271498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.834309Z","time spent":"437.09718ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:11:58.271707Z","caller":"traceutil/trace.go:171","msg":"trace[574229716] transaction","detail":"{read_only:false; response_revision:25009; number_of_response:1; }","duration":"406.640914ms","start":"2026-08-04T16:11:57.865058Z","end":"2026-08-04T16:11:58.271699Z","steps":["trace[574229716] 'process raft request' (duration: 405.841361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.271756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.865034Z","time spent":"406.696026ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:11:58.271858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.199054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-04T16:11:58.271905Z","caller":"traceutil/trace.go:171","msg":"trace[4932862] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25009; }","duration":"403.296297ms","start":"2026-08-04T16:11:57.868598Z","end":"2026-08-04T16:11:58.271895Z","steps":["trace[4932862] 'agreement among raft nodes before linearized reading' (duration: 403.217215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.271948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.868567Z","time spent":"403.37546ms","remote":"127.0.0.1:47466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":32,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:11:58.272101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.162094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:11:58.272127Z","caller":"traceutil/trace.go:171","msg":"trace[1280892637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25009; }","duration":"414.236406ms","start":"2026-08-04T16:11:57.857884Z","end":"2026-08-04T16:11:58.27212Z","steps":["trace[1280892637] 'agreement among raft nodes before linearized reading' (duration: 414.188435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.27215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.857841Z","time spent":"414.305028ms","remote":"127.0.0.1:47012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T16:11:58.272304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.365871ms","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":"info","ts":"2026-08-04T16:11:58.272401Z","caller":"traceutil/trace.go:171","msg":"trace[1067217217] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25009; }","duration":"540.506955ms","start":"2026-08-04T16:11:57.731872Z","end":"2026-08-04T16:11:58.272378Z","steps":["trace[1067217217] 'agreement among raft nodes before linearized reading' (duration: 540.32989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.272456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.731854Z","time spent":"540.591427ms","remote":"127.0.0.1:35284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:11:58.272653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.441172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:11:58.272683Z","caller":"traceutil/trace.go:171","msg":"trace[523581733] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25009; }","duration":"636.493653ms","start":"2026-08-04T16:11:57.636181Z","end":"2026-08-04T16:11:58.272675Z","steps":["trace[523581733] 'agreement among raft nodes before linearized reading' (duration: 636.438052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.272704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.636148Z","time spent":"636.549945ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":30,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:11:58.309907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.768995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2d8328e7-9628-4a03-b6a0-dd8470fc83b5\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-08-04T16:11:58.309995Z","caller":"traceutil/trace.go:171","msg":"trace[1428553253] transaction","detail":"{read_only:false; response_revision:25010; number_of_response:1; }","duration":"207.220091ms","start":"2026-08-04T16:11:58.102751Z","end":"2026-08-04T16:11:58.309971Z","steps":["trace[1428553253] 'process raft request' (duration: 206.758258ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:58.309997Z","caller":"traceutil/trace.go:171","msg":"trace[1099379268] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2d8328e7-9628-4a03-b6a0-dd8470fc83b5; range_end:; response_count:1; response_revision:25010; }","duration":"389.889389ms","start":"2026-08-04T16:11:57.92007Z","end":"2026-08-04T16:11:58.30996Z","steps":["trace[1099379268] 'agreement among raft nodes before linearized reading' (duration: 389.668622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.310193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.920057Z","time spent":"390.125055ms","remote":"127.0.0.1:47164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-2d8328e7-9628-4a03-b6a0-dd8470fc83b5\" "} {"level":"warn","ts":"2026-08-04T16:11:58.309902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.889237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:11:58.31042Z","caller":"traceutil/trace.go:171","msg":"trace[1677922450] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25010; }","duration":"370.469724ms","start":"2026-08-04T16:11:57.939922Z","end":"2026-08-04T16:11:58.310392Z","steps":["trace[1677922450] 'agreement among raft nodes before linearized reading' (duration: 369.816015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.310504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:57.939911Z","time spent":"370.561586ms","remote":"127.0.0.1:60538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:11:58.646781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.824522ms","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-08-04T16:11:58.646879Z","caller":"traceutil/trace.go:171","msg":"trace[559498905] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25010; }","duration":"277.976077ms","start":"2026-08-04T16:11:58.368881Z","end":"2026-08-04T16:11:58.646857Z","steps":["trace[559498905] 'range keys from in-memory index tree' (duration: 277.621836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.647635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.45989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:11:58.647732Z","caller":"traceutil/trace.go:171","msg":"trace[621720323] transaction","detail":"{read_only:false; response_revision:25011; number_of_response:1; }","duration":"259.023066ms","start":"2026-08-04T16:11:58.388691Z","end":"2026-08-04T16:11:58.647714Z","steps":["trace[621720323] 'process raft request' (duration: 52.369212ms)","trace[621720323] 'compare' (duration: 206.309934ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:58.880869Z","caller":"traceutil/trace.go:171","msg":"trace[376562926] linearizableReadLoop","detail":"{readStateIndex:25830; appliedIndex:25829; }","duration":"147.569138ms","start":"2026-08-04T16:11:58.73328Z","end":"2026-08-04T16:11:58.880849Z","steps":["trace[376562926] 'read index received' (duration: 60.90082ms)","trace[376562926] 'applied index is now lower than readState.Index' (duration: 86.667378ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:58.881092Z","caller":"traceutil/trace.go:171","msg":"trace[1481431446] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"174.271624ms","start":"2026-08-04T16:11:58.706808Z","end":"2026-08-04T16:11:58.881079Z","steps":["trace[1481431446] 'process raft request' (duration: 87.459311ms)","trace[1481431446] 'compare' (duration: 86.268747ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:11:58.881278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.98511ms","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-08-04T16:11:58.881307Z","caller":"traceutil/trace.go:171","msg":"trace[2102577998] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25012; }","duration":"148.067512ms","start":"2026-08-04T16:11:58.733232Z","end":"2026-08-04T16:11:58.881299Z","steps":["trace[2102577998] 'agreement among raft nodes before linearized reading' (duration: 148.0042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:58.881509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.456839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-04T16:11:58.881536Z","caller":"traceutil/trace.go:171","msg":"trace[795474847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25012; }","duration":"111.484169ms","start":"2026-08-04T16:11:58.770044Z","end":"2026-08-04T16:11:58.881528Z","steps":["trace[795474847] 'agreement among raft nodes before linearized reading' (duration: 111.394507ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:59.176564Z","caller":"traceutil/trace.go:171","msg":"trace[313411305] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"289.517892ms","start":"2026-08-04T16:11:58.887018Z","end":"2026-08-04T16:11:59.176536Z","steps":["trace[313411305] 'process raft request' (duration: 289.402548ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:59.286925Z","caller":"traceutil/trace.go:171","msg":"trace[1707728793] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25831; }","duration":"396.783439ms","start":"2026-08-04T16:11:58.890123Z","end":"2026-08-04T16:11:59.286906Z","steps":["trace[1707728793] 'read index received' (duration: 286.439782ms)","trace[1707728793] 'applied index is now lower than readState.Index' (duration: 110.342877ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:11:59.287642Z","caller":"traceutil/trace.go:171","msg":"trace[680354932] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"399.295292ms","start":"2026-08-04T16:11:58.887872Z","end":"2026-08-04T16:11:59.287167Z","steps":["trace[680354932] 'process raft request' (duration: 398.88075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.287756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:58.887854Z","time spent":"399.825397ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:11:59.287924Z","caller":"traceutil/trace.go:171","msg":"trace[2111928315] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"399.969721ms","start":"2026-08-04T16:11:58.887944Z","end":"2026-08-04T16:11:59.287914Z","steps":["trace[2111928315] 'process raft request' (duration: 398.929381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.287974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:58.887933Z","time spent":"400.011383ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:11:59.28811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.984284ms","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-08-04T16:11:59.288136Z","caller":"traceutil/trace.go:171","msg":"trace[1655897607] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25015; }","duration":"398.020074ms","start":"2026-08-04T16:11:58.890107Z","end":"2026-08-04T16:11:59.288127Z","steps":["trace[1655897607] 'agreement among raft nodes before linearized reading' (duration: 397.924311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.288158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:58.890102Z","time spent":"398.051115ms","remote":"127.0.0.1:47206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-04T16:11:59.672227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.504478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:11:59.672295Z","caller":"traceutil/trace.go:171","msg":"trace[1826334865] linearizableReadLoop","detail":"{readStateIndex:25834; appliedIndex:25833; }","duration":"385.295074ms","start":"2026-08-04T16:11:59.286989Z","end":"2026-08-04T16:11:59.672284Z","steps":["trace[1826334865] 'read index received' (duration: 124.544508ms)","trace[1826334865] 'applied index is now lower than readState.Index' (duration: 260.749826ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:11:59.672488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.847923ms","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-08-04T16:11:59.672503Z","caller":"traceutil/trace.go:171","msg":"trace[613454357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25016; }","duration":"510.901314ms","start":"2026-08-04T16:11:59.161597Z","end":"2026-08-04T16:11:59.672498Z","steps":["trace[613454357] 'agreement among raft nodes before linearized reading' (duration: 510.818742ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:59.672472Z","caller":"traceutil/trace.go:171","msg":"trace[380263208] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"458.491531ms","start":"2026-08-04T16:11:59.213952Z","end":"2026-08-04T16:11:59.672443Z","steps":["trace[380263208] 'process raft request' (duration: 197.719745ms)","trace[380263208] 'compare' (duration: 259.999024ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:11:59.672507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.500324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T16:11:59.672523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.161577Z","time spent":"510.942346ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T16:11:59.672516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.401338ms","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-08-04T16:11:59.672507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.807475ms","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-08-04T16:11:59.672547Z","caller":"traceutil/trace.go:171","msg":"trace[1779850984] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25016; }","duration":"455.576436ms","start":"2026-08-04T16:11:59.216958Z","end":"2026-08-04T16:11:59.672535Z","steps":["trace[1779850984] 'agreement among raft nodes before linearized reading' (duration: 455.464353ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:11:59.67258Z","caller":"traceutil/trace.go:171","msg":"trace[1976542986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25016; }","duration":"459.507131ms","start":"2026-08-04T16:11:59.213056Z","end":"2026-08-04T16:11:59.672563Z","steps":["trace[1976542986] 'agreement among raft nodes before linearized reading' (duration: 459.327766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.672589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.21695Z","time spent":"455.629957ms","remote":"127.0.0.1:56148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:11:59.672593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.21394Z","time spent":"458.595174ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:11:59.672597Z","caller":"traceutil/trace.go:171","msg":"trace[1536742383] 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:25016; }","duration":"738.946919ms","start":"2026-08-04T16:11:58.933633Z","end":"2026-08-04T16:11:59.67258Z","steps":["trace[1536742383] 'agreement among raft nodes before linearized reading' (duration: 738.791705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.672592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.775828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T16:11:59.672619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.213038Z","time spent":"459.572564ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-04T16:11:59.672641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:58.933617Z","time spent":"739.012213ms","remote":"127.0.0.1:60784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-08-04T16:11:59.672655Z","caller":"traceutil/trace.go:171","msg":"trace[1118924943] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:25016; }","duration":"693.891162ms","start":"2026-08-04T16:11:58.978747Z","end":"2026-08-04T16:11:59.672638Z","steps":["trace[1118924943] 'agreement among raft nodes before linearized reading' (duration: 693.752527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.672696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:58.978728Z","time spent":"693.961173ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:11:59.672634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.800458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:11:59.672866Z","caller":"traceutil/trace.go:171","msg":"trace[568042384] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25016; }","duration":"386.16666ms","start":"2026-08-04T16:11:59.286679Z","end":"2026-08-04T16:11:59.672846Z","steps":["trace[568042384] 'agreement among raft nodes before linearized reading' (duration: 385.741777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.672915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.286663Z","time spent":"386.238363ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"info","ts":"2026-08-04T16:11:59.692622Z","caller":"traceutil/trace.go:171","msg":"trace[963646375] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"106.212816ms","start":"2026-08-04T16:11:59.586392Z","end":"2026-08-04T16:11:59.692605Z","steps":["trace[963646375] 'process raft request' (duration: 106.096863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.692922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.314558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-08-04T16:11:59.692954Z","caller":"traceutil/trace.go:171","msg":"trace[1267023289] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25017; }","duration":"403.384311ms","start":"2026-08-04T16:11:59.289563Z","end":"2026-08-04T16:11:59.692947Z","steps":["trace[1267023289] 'agreement among raft nodes before linearized reading' (duration: 403.214616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:11:59.692973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.289552Z","time spent":"403.416442ms","remote":"127.0.0.1:47192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18254,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-04T16:12:00.18435Z","caller":"traceutil/trace.go:171","msg":"trace[1006555685] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"507.399923ms","start":"2026-08-04T16:11:59.676935Z","end":"2026-08-04T16:12:00.184335Z","steps":["trace[1006555685] 'process raft request' (duration: 507.085934ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:00.184459Z","caller":"traceutil/trace.go:171","msg":"trace[1897602697] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"507.032102ms","start":"2026-08-04T16:11:59.677372Z","end":"2026-08-04T16:12:00.184405Z","steps":["trace[1897602697] 'process raft request' (duration: 506.826326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.184568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.676912Z","time spent":"507.508695ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:12:00.184614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.471265ms","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-08-04T16:12:00.184712Z","caller":"traceutil/trace.go:171","msg":"trace[2145619542] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25019; }","duration":"507.606268ms","start":"2026-08-04T16:11:59.677083Z","end":"2026-08-04T16:12:00.184689Z","steps":["trace[2145619542] 'agreement among raft nodes before linearized reading' (duration: 507.417423ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.184727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.677367Z","time spent":"507.223927ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:12:00.184777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.677076Z","time spent":"507.688721ms","remote":"127.0.0.1:47218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-08-04T16:12:00.18517Z","caller":"traceutil/trace.go:171","msg":"trace[1842178759] linearizableReadLoop","detail":"{readStateIndex:25837; appliedIndex:25835; }","duration":"491.52158ms","start":"2026-08-04T16:11:59.692758Z","end":"2026-08-04T16:12:00.184279Z","steps":["trace[1842178759] 'read index received' (duration: 491.346365ms)","trace[1842178759] 'applied index is now lower than readState.Index' (duration: 174.415µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:00.201477Z","caller":"traceutil/trace.go:171","msg":"trace[745242836] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"274.428254ms","start":"2026-08-04T16:11:59.927023Z","end":"2026-08-04T16:12:00.201451Z","steps":["trace[745242836] 'process raft request' (duration: 273.893949ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.201954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.829355ms","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-08-04T16:12:00.20208Z","caller":"traceutil/trace.go:171","msg":"trace[494016837] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25020; }","duration":"274.975039ms","start":"2026-08-04T16:11:59.927085Z","end":"2026-08-04T16:12:00.20206Z","steps":["trace[494016837] 'agreement among raft nodes before linearized reading' (duration: 274.759863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.202432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.654624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:00.202525Z","caller":"traceutil/trace.go:171","msg":"trace[1008679556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25020; }","duration":"345.828669ms","start":"2026-08-04T16:11:59.856675Z","end":"2026-08-04T16:12:00.202503Z","steps":["trace[1008679556] 'agreement among raft nodes before linearized reading' (duration: 345.638404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.202593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.85663Z","time spent":"345.954542ms","remote":"127.0.0.1:47018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T16:12:00.202698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.374621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-04T16:12:00.202735Z","caller":"traceutil/trace.go:171","msg":"trace[18917298] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25020; }","duration":"497.432653ms","start":"2026-08-04T16:11:59.705294Z","end":"2026-08-04T16:12:00.202726Z","steps":["trace[18917298] 'agreement among raft nodes before linearized reading' (duration: 497.338281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.202757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.705277Z","time spent":"497.474384ms","remote":"127.0.0.1:54184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5676,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-04T16:12:00.20292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.451928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:00.202953Z","caller":"traceutil/trace.go:171","msg":"trace[1544179602] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25020; }","duration":"355.51714ms","start":"2026-08-04T16:11:59.847426Z","end":"2026-08-04T16:12:00.202943Z","steps":["trace[1544179602] 'agreement among raft nodes before linearized reading' (duration: 355.456488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.202975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:11:59.847407Z","time spent":"355.5611ms","remote":"127.0.0.1:60724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-08-04T16:12:00.469411Z","caller":"traceutil/trace.go:171","msg":"trace[849675562] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"192.332918ms","start":"2026-08-04T16:12:00.277056Z","end":"2026-08-04T16:12:00.469389Z","steps":["trace[849675562] 'process raft request' (duration: 192.256776ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:00.469711Z","caller":"traceutil/trace.go:171","msg":"trace[1126113335] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"261.074855ms","start":"2026-08-04T16:12:00.208619Z","end":"2026-08-04T16:12:00.469694Z","steps":["trace[1126113335] 'process raft request' (duration: 165.338173ms)","trace[1126113335] 'compare' (duration: 94.992171ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:00.469924Z","caller":"traceutil/trace.go:171","msg":"trace[866510965] linearizableReadLoop","detail":"{readStateIndex:25839; appliedIndex:25838; }","duration":"261.078545ms","start":"2026-08-04T16:12:00.20879Z","end":"2026-08-04T16:12:00.469868Z","steps":["trace[866510965] 'read index received' (duration: 165.163178ms)","trace[866510965] 'applied index is now lower than readState.Index' (duration: 95.913417ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:00.490855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.027665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:00.49095Z","caller":"traceutil/trace.go:171","msg":"trace[290178792] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25022; }","duration":"282.165488ms","start":"2026-08-04T16:12:00.208761Z","end":"2026-08-04T16:12:00.490927Z","steps":["trace[290178792] 'agreement among raft nodes before linearized reading' (duration: 261.25504ms)","trace[290178792] 'range keys from in-memory index tree' (duration: 20.763164ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:00.491124Z","caller":"traceutil/trace.go:171","msg":"trace[1465343019] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"175.595493ms","start":"2026-08-04T16:12:00.315506Z","end":"2026-08-04T16:12:00.491102Z","steps":["trace[1465343019] 'process raft request' (duration: 175.253583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:00.806627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.729445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274805"} {"level":"info","ts":"2026-08-04T16:12:00.806685Z","caller":"traceutil/trace.go:171","msg":"trace[1717412770] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25023; }","duration":"218.800107ms","start":"2026-08-04T16:12:00.587872Z","end":"2026-08-04T16:12:00.806672Z","steps":["trace[1717412770] 'range keys from in-memory index tree' (duration: 218.391525ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:00.903776Z","caller":"traceutil/trace.go:171","msg":"trace[1609666644] linearizableReadLoop","detail":"{readStateIndex:25843; appliedIndex:25843; }","duration":"218.089357ms","start":"2026-08-04T16:12:00.685647Z","end":"2026-08-04T16:12:00.903737Z","steps":["trace[1609666644] 'read index received' (duration: 218.072026ms)","trace[1609666644] 'applied index is now lower than readState.Index' (duration: 14.62µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:00.90402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.344675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:00.904065Z","caller":"traceutil/trace.go:171","msg":"trace[193432303] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25024; }","duration":"218.447367ms","start":"2026-08-04T16:12:00.685606Z","end":"2026-08-04T16:12:00.904054Z","steps":["trace[193432303] 'agreement among raft nodes before linearized reading' (duration: 218.292572ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:00.905959Z","caller":"traceutil/trace.go:171","msg":"trace[1913477349] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"250.944462ms","start":"2026-08-04T16:12:00.65267Z","end":"2026-08-04T16:12:00.903615Z","steps":["trace[1913477349] 'process raft request' (duration: 250.834439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:01.073946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.429808ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T16:12:01.074058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.351944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:12:01.074124Z","caller":"traceutil/trace.go:171","msg":"trace[84013823] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25024; }","duration":"164.620703ms","start":"2026-08-04T16:12:00.909473Z","end":"2026-08-04T16:12:01.074094Z","steps":["trace[84013823] 'range keys from in-memory index tree' (duration: 164.407937ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:01.074141Z","caller":"traceutil/trace.go:171","msg":"trace[1929105323] linearizableReadLoop","detail":"{readStateIndex:25844; appliedIndex:25843; }","duration":"170.266047ms","start":"2026-08-04T16:12:00.903861Z","end":"2026-08-04T16:12:01.074127Z","steps":["trace[1929105323] 'read index received' (duration: 14.726098ms)","trace[1929105323] 'applied index is now lower than readState.Index' (duration: 155.539039ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:01.074246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.359976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:01.074328Z","caller":"traceutil/trace.go:171","msg":"trace[131802665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25025; }","duration":"217.405587ms","start":"2026-08-04T16:12:00.85686Z","end":"2026-08-04T16:12:01.074265Z","steps":["trace[131802665] 'agreement among raft nodes before linearized reading' (duration: 217.304864ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:01.074633Z","caller":"traceutil/trace.go:171","msg":"trace[1029567282] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"185.481819ms","start":"2026-08-04T16:12:00.889132Z","end":"2026-08-04T16:12:01.074614Z","steps":["trace[1029567282] 'process raft request' (duration: 29.499557ms)","trace[1029567282] 'compare' (duration: 155.270252ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:01.250425Z","caller":"traceutil/trace.go:171","msg":"trace[1300474930] linearizableReadLoop","detail":"{readStateIndex:25845; appliedIndex:25844; }","duration":"173.667266ms","start":"2026-08-04T16:12:01.076728Z","end":"2026-08-04T16:12:01.250395Z","steps":["trace[1300474930] 'read index received' (duration: 122.378815ms)","trace[1300474930] 'applied index is now lower than readState.Index' (duration: 51.287351ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:01.250568Z","caller":"traceutil/trace.go:171","msg":"trace[1839332019] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"263.867917ms","start":"2026-08-04T16:12:00.986687Z","end":"2026-08-04T16:12:01.250555Z","steps":["trace[1839332019] 'process raft request' (duration: 212.480763ms)","trace[1839332019] 'compare' (duration: 50.891739ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:01.250693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.892285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11835"} {"level":"info","ts":"2026-08-04T16:12:01.250751Z","caller":"traceutil/trace.go:171","msg":"trace[1850667151] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25026; }","duration":"170.984498ms","start":"2026-08-04T16:12:01.079752Z","end":"2026-08-04T16:12:01.250737Z","steps":["trace[1850667151] 'agreement among raft nodes before linearized reading' (duration: 170.831313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:01.250915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.190161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:01.25094Z","caller":"traceutil/trace.go:171","msg":"trace[628834918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25026; }","duration":"174.215222ms","start":"2026-08-04T16:12:01.076718Z","end":"2026-08-04T16:12:01.250934Z","steps":["trace[628834918] 'agreement among raft nodes before linearized reading' (duration: 174.17612ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:01.40211Z","caller":"traceutil/trace.go:171","msg":"trace[838266490] linearizableReadLoop","detail":"{readStateIndex:25846; appliedIndex:25845; }","duration":"144.077027ms","start":"2026-08-04T16:12:01.25801Z","end":"2026-08-04T16:12:01.402087Z","steps":["trace[838266490] 'read index received' (duration: 120.946296ms)","trace[838266490] 'applied index is now lower than readState.Index' (duration: 23.129991ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:01.402292Z","caller":"traceutil/trace.go:171","msg":"trace[1409295068] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"146.999821ms","start":"2026-08-04T16:12:01.255218Z","end":"2026-08-04T16:12:01.402218Z","steps":["trace[1409295068] 'process raft request' (duration: 123.835998ms)","trace[1409295068] 'compare' (duration: 22.921756ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:01.402352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.310004ms","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-08-04T16:12:01.402451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.968275ms","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-08-04T16:12:01.402506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.448878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-04T16:12:01.402559Z","caller":"traceutil/trace.go:171","msg":"trace[515538772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25027; }","duration":"111.094798ms","start":"2026-08-04T16:12:01.291431Z","end":"2026-08-04T16:12:01.402526Z","steps":["trace[515538772] 'agreement among raft nodes before linearized reading' (duration: 110.853621ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:01.402583Z","caller":"traceutil/trace.go:171","msg":"trace[844554904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25027; }","duration":"110.563052ms","start":"2026-08-04T16:12:01.292003Z","end":"2026-08-04T16:12:01.402566Z","steps":["trace[844554904] 'agreement among raft nodes before linearized reading' (duration: 110.326985ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:01.402493Z","caller":"traceutil/trace.go:171","msg":"trace[890654881] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25027; }","duration":"144.486028ms","start":"2026-08-04T16:12:01.257994Z","end":"2026-08-04T16:12:01.40248Z","steps":["trace[890654881] 'agreement among raft nodes before linearized reading' (duration: 144.20834ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:01.866965Z","caller":"traceutil/trace.go:171","msg":"trace[75186518] linearizableReadLoop","detail":"{readStateIndex:25848; appliedIndex:25847; }","duration":"348.330091ms","start":"2026-08-04T16:12:01.518616Z","end":"2026-08-04T16:12:01.866946Z","steps":["trace[75186518] 'read index received' (duration: 348.199437ms)","trace[75186518] 'applied index is now lower than readState.Index' (duration: 129.604µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:01.867086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.463495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:01.867136Z","caller":"traceutil/trace.go:171","msg":"trace[723533412] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25029; }","duration":"348.570518ms","start":"2026-08-04T16:12:01.518558Z","end":"2026-08-04T16:12:01.867128Z","steps":["trace[723533412] 'agreement among raft nodes before linearized reading' (duration: 348.475925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:01.86717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:01.518538Z","time spent":"348.62238ms","remote":"127.0.0.1:60550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"info","ts":"2026-08-04T16:12:01.867005Z","caller":"traceutil/trace.go:171","msg":"trace[679744210] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"459.094479ms","start":"2026-08-04T16:12:01.407887Z","end":"2026-08-04T16:12:01.866981Z","steps":["trace[679744210] 'process raft request' (duration: 458.928794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:01.867469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:01.407861Z","time spent":"459.530582ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:01.884135Z","caller":"traceutil/trace.go:171","msg":"trace[1117766193] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"204.638346ms","start":"2026-08-04T16:12:01.67948Z","end":"2026-08-04T16:12:01.884119Z","steps":["trace[1117766193] 'process raft request' (duration: 204.478221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:01.884246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.670741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-c5f456fbc-2xvz2.18c8a451645ce33f\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-08-04T16:12:01.884301Z","caller":"traceutil/trace.go:171","msg":"trace[317093323] range","detail":"{range_begin:/registry/events/openstack/barbican-api-c5f456fbc-2xvz2.18c8a451645ce33f; range_end:; response_count:1; response_revision:25031; }","duration":"146.769884ms","start":"2026-08-04T16:12:01.737517Z","end":"2026-08-04T16:12:01.884287Z","steps":["trace[317093323] 'agreement among raft nodes before linearized reading' (duration: 146.570718ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:01.884584Z","caller":"traceutil/trace.go:171","msg":"trace[1127738799] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"186.906071ms","start":"2026-08-04T16:12:01.697666Z","end":"2026-08-04T16:12:01.884573Z","steps":["trace[1127738799] 'process raft request' (duration: 186.377456ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:02.319576Z","caller":"traceutil/trace.go:171","msg":"trace[1821209290] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"126.466794ms","start":"2026-08-04T16:12:02.193087Z","end":"2026-08-04T16:12:02.319554Z","steps":["trace[1821209290] 'process raft request' (duration: 126.350561ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:02.500703Z","caller":"traceutil/trace.go:171","msg":"trace[293762029] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"305.377193ms","start":"2026-08-04T16:12:02.195291Z","end":"2026-08-04T16:12:02.500668Z","steps":["trace[293762029] 'process raft request' (duration: 302.824229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:02.501034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:02.195265Z","time spent":"305.518057ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:02.500736Z","caller":"traceutil/trace.go:171","msg":"trace[1934218720] transaction","detail":"{read_only:false; response_revision:25035; number_of_response:1; }","duration":"292.040245ms","start":"2026-08-04T16:12:02.208665Z","end":"2026-08-04T16:12:02.500706Z","steps":["trace[1934218720] 'process raft request' (duration: 291.782717ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:12.826153Z","caller":"traceutil/trace.go:171","msg":"trace[1559943413] linearizableReadLoop","detail":"{readStateIndex:25930; appliedIndex:25928; }","duration":"265.70036ms","start":"2026-08-04T16:12:12.560428Z","end":"2026-08-04T16:12:12.826129Z","steps":["trace[1559943413] 'read index received' (duration: 265.463193ms)","trace[1559943413] 'applied index is now lower than readState.Index' (duration: 236.377µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:12.82631Z","caller":"traceutil/trace.go:171","msg":"trace[90076642] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"270.86148ms","start":"2026-08-04T16:12:12.555437Z","end":"2026-08-04T16:12:12.826298Z","steps":["trace[90076642] 'process raft request' (duration: 270.654084ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:12.826231Z","caller":"traceutil/trace.go:171","msg":"trace[850371789] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"276.046071ms","start":"2026-08-04T16:12:12.550147Z","end":"2026-08-04T16:12:12.826193Z","steps":["trace[850371789] 'process raft request' (duration: 275.797974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:12.826621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.175983ms","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-08-04T16:12:12.826708Z","caller":"traceutil/trace.go:171","msg":"trace[125724148] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25109; }","duration":"266.302648ms","start":"2026-08-04T16:12:12.560382Z","end":"2026-08-04T16:12:12.826685Z","steps":["trace[125724148] 'agreement among raft nodes before linearized reading' (duration: 266.088292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:12.827507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.354839ms","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-08-04T16:12:12.827584Z","caller":"traceutil/trace.go:171","msg":"trace[1599625153] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25109; }","duration":"266.489103ms","start":"2026-08-04T16:12:12.561073Z","end":"2026-08-04T16:12:12.827562Z","steps":["trace[1599625153] 'agreement among raft nodes before linearized reading' (duration: 265.402362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:13.391217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.953966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:12:13.391398Z","caller":"traceutil/trace.go:171","msg":"trace[1013076892] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"557.927161ms","start":"2026-08-04T16:12:12.833447Z","end":"2026-08-04T16:12:13.391375Z","steps":["trace[1013076892] 'process raft request' (duration: 279.742108ms)","trace[1013076892] 'compare' (duration: 277.74822ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:13.391497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:12.833427Z","time spent":"558.026725ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:13.391581Z","caller":"traceutil/trace.go:171","msg":"trace[216215055] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"446.664408ms","start":"2026-08-04T16:12:12.944874Z","end":"2026-08-04T16:12:13.391538Z","steps":["trace[216215055] 'process raft request' (duration: 446.446932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:13.392076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:12.944847Z","time spent":"447.146422ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:13.393382Z","caller":"traceutil/trace.go:171","msg":"trace[9220176] linearizableReadLoop","detail":"{readStateIndex:25934; appliedIndex:25931; }","duration":"219.867938ms","start":"2026-08-04T16:12:13.173486Z","end":"2026-08-04T16:12:13.393354Z","steps":["trace[9220176] 'read index received' (duration: 39.112067ms)","trace[9220176] 'applied index is now lower than readState.Index' (duration: 180.754651ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:13.393594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.024872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:13.393649Z","caller":"traceutil/trace.go:171","msg":"trace[1222870526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25113; }","duration":"220.203287ms","start":"2026-08-04T16:12:13.173431Z","end":"2026-08-04T16:12:13.393635Z","steps":["trace[1222870526] 'agreement among raft nodes before linearized reading' (duration: 220.028292ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:13.393744Z","caller":"traceutil/trace.go:171","msg":"trace[803781633] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"278.765999ms","start":"2026-08-04T16:12:13.114931Z","end":"2026-08-04T16:12:13.393696Z","steps":["trace[803781633] 'process raft request' (duration: 278.083729ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:13.709027Z","caller":"traceutil/trace.go:171","msg":"trace[590409424] linearizableReadLoop","detail":"{readStateIndex:25936; appliedIndex:25934; }","duration":"266.538384ms","start":"2026-08-04T16:12:13.442467Z","end":"2026-08-04T16:12:13.709005Z","steps":["trace[590409424] 'read index received' (duration: 266.335448ms)","trace[590409424] 'applied index is now lower than readState.Index' (duration: 201.976µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:13.709499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.006417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T16:12:13.709536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.865113ms","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-08-04T16:12:13.709581Z","caller":"traceutil/trace.go:171","msg":"trace[1210820907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25115; }","duration":"235.962326ms","start":"2026-08-04T16:12:13.473604Z","end":"2026-08-04T16:12:13.709567Z","steps":["trace[1210820907] 'agreement among raft nodes before linearized reading' (duration: 235.810302ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:13.709583Z","caller":"traceutil/trace.go:171","msg":"trace[642269184] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25115; }","duration":"267.138422ms","start":"2026-08-04T16:12:13.442422Z","end":"2026-08-04T16:12:13.709561Z","steps":["trace[642269184] 'agreement among raft nodes before linearized reading' (duration: 266.984887ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:13.709885Z","caller":"traceutil/trace.go:171","msg":"trace[1729633559] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"391.420202ms","start":"2026-08-04T16:12:13.318424Z","end":"2026-08-04T16:12:13.709845Z","steps":["trace[1729633559] 'process raft request' (duration: 390.530066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:13.710006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.073388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-04T16:12:13.710058Z","caller":"traceutil/trace.go:171","msg":"trace[1584971418] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25115; }","duration":"110.155441ms","start":"2026-08-04T16:12:13.599887Z","end":"2026-08-04T16:12:13.710043Z","steps":["trace[1584971418] 'agreement among raft nodes before linearized reading' (duration: 110.020967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:13.71009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:13.318401Z","time spent":"391.564857ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:13.710162Z","caller":"traceutil/trace.go:171","msg":"trace[50977607] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"420.500648ms","start":"2026-08-04T16:12:13.289622Z","end":"2026-08-04T16:12:13.710122Z","steps":["trace[50977607] 'process raft request' (duration: 419.1909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:13.710232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.113749ms","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-08-04T16:12:13.710445Z","caller":"traceutil/trace.go:171","msg":"trace[2028270029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25115; }","duration":"185.371737ms","start":"2026-08-04T16:12:13.525053Z","end":"2026-08-04T16:12:13.710425Z","steps":["trace[2028270029] 'agreement among raft nodes before linearized reading' (duration: 185.054747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:13.711135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:13.289257Z","time spent":"420.986262ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:12:14.26916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.061241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:12:14.269263Z","caller":"traceutil/trace.go:171","msg":"trace[829170337] linearizableReadLoop","detail":"{readStateIndex:25938; appliedIndex:25937; }","duration":"411.351142ms","start":"2026-08-04T16:12:13.857898Z","end":"2026-08-04T16:12:14.269249Z","steps":["trace[829170337] 'read index received' (duration: 134.114717ms)","trace[829170337] 'applied index is now lower than readState.Index' (duration: 277.234895ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:14.269312Z","caller":"traceutil/trace.go:171","msg":"trace[158191993] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"554.255484ms","start":"2026-08-04T16:12:13.715047Z","end":"2026-08-04T16:12:14.269302Z","steps":["trace[158191993] 'process raft request' (duration: 276.950716ms)","trace[158191993] 'compare' (duration: 276.745562ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:14.26935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:13.715022Z","time spent":"554.306186ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:12:14.269511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.61675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:14.269535Z","caller":"traceutil/trace.go:171","msg":"trace[308268888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25117; }","duration":"411.679951ms","start":"2026-08-04T16:12:13.857846Z","end":"2026-08-04T16:12:14.269526Z","steps":["trace[308268888] 'agreement among raft nodes before linearized reading' (duration: 411.64ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:14.269553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:13.857821Z","time spent":"411.728603ms","remote":"127.0.0.1:47018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T16:12:14.354597Z","caller":"traceutil/trace.go:171","msg":"trace[748653254] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"433.611818ms","start":"2026-08-04T16:12:13.92096Z","end":"2026-08-04T16:12:14.354572Z","steps":["trace[748653254] 'process raft request' (duration: 433.396282ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:14.354663Z","caller":"traceutil/trace.go:171","msg":"trace[1014874869] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"433.536365ms","start":"2026-08-04T16:12:13.921087Z","end":"2026-08-04T16:12:14.354624Z","steps":["trace[1014874869] 'process raft request' (duration: 433.411132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:14.354705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.502331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-04T16:12:14.354789Z","caller":"traceutil/trace.go:171","msg":"trace[2007177697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25119; }","duration":"438.635275ms","start":"2026-08-04T16:12:13.916136Z","end":"2026-08-04T16:12:14.354771Z","steps":["trace[2007177697] 'agreement among raft nodes before linearized reading' (duration: 438.416478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:14.354841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:13.916115Z","time spent":"438.716287ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-04T16:12:14.354727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:13.920931Z","time spent":"433.723281ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:12:14.354834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:13.921072Z","time spent":"433.672181ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:14.557739Z","caller":"traceutil/trace.go:171","msg":"trace[667638021] linearizableReadLoop","detail":"{readStateIndex:25941; appliedIndex:25940; }","duration":"173.947584ms","start":"2026-08-04T16:12:14.383764Z","end":"2026-08-04T16:12:14.557712Z","steps":["trace[667638021] 'read index received' (duration: 173.169252ms)","trace[667638021] 'applied index is now lower than readState.Index' (duration: 776.292µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:14.557997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.218042ms","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-08-04T16:12:14.558053Z","caller":"traceutil/trace.go:171","msg":"trace[1964638107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25120; }","duration":"174.331295ms","start":"2026-08-04T16:12:14.383707Z","end":"2026-08-04T16:12:14.558038Z","steps":["trace[1964638107] 'agreement among raft nodes before linearized reading' (duration: 174.131299ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:14.558459Z","caller":"traceutil/trace.go:171","msg":"trace[430760604] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"198.856598ms","start":"2026-08-04T16:12:14.359572Z","end":"2026-08-04T16:12:14.558429Z","steps":["trace[430760604] 'process raft request' (duration: 197.437737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:15.054676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:14.6609Z","time spent":"393.76701ms","remote":"127.0.0.1:39104","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-04T16:12:15.05531Z","caller":"traceutil/trace.go:171","msg":"trace[856889235] linearizableReadLoop","detail":"{readStateIndex:25945; appliedIndex:25942; }","duration":"197.564981ms","start":"2026-08-04T16:12:14.857712Z","end":"2026-08-04T16:12:15.055277Z","steps":["trace[856889235] 'read index received' (duration: 168.021282ms)","trace[856889235] 'applied index is now lower than readState.Index' (duration: 29.542149ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:15.055601Z","caller":"traceutil/trace.go:171","msg":"trace[499418330] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"224.101021ms","start":"2026-08-04T16:12:14.831465Z","end":"2026-08-04T16:12:15.055567Z","steps":["trace[499418330] 'process raft request' (duration: 223.684339ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:15.055668Z","caller":"traceutil/trace.go:171","msg":"trace[766968225] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"222.896676ms","start":"2026-08-04T16:12:14.83275Z","end":"2026-08-04T16:12:15.055647Z","steps":["trace[766968225] 'process raft request' (duration: 222.474064ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:15.055836Z","caller":"traceutil/trace.go:171","msg":"trace[1556786084] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"401.880568ms","start":"2026-08-04T16:12:14.653909Z","end":"2026-08-04T16:12:15.055789Z","steps":["trace[1556786084] 'process raft request' (duration: 371.736852ms)","trace[1556786084] 'compare' (duration: 29.28881ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:15.055893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.158468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:15.055934Z","caller":"traceutil/trace.go:171","msg":"trace[1710340187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25124; }","duration":"198.25876ms","start":"2026-08-04T16:12:14.857663Z","end":"2026-08-04T16:12:15.055922Z","steps":["trace[1710340187] 'agreement among raft nodes before linearized reading' (duration: 198.162618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:15.055959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.908853ms","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-08-04T16:12:15.056011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:14.653881Z","time spent":"402.036132ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:15.056028Z","caller":"traceutil/trace.go:171","msg":"trace[1560567803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25124; }","duration":"143.032806ms","start":"2026-08-04T16:12:14.912976Z","end":"2026-08-04T16:12:15.056009Z","steps":["trace[1560567803] 'agreement among raft nodes before linearized reading' (duration: 142.81987ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:20.013319Z","caller":"traceutil/trace.go:171","msg":"trace[1439678259] linearizableReadLoop","detail":"{readStateIndex:26041; appliedIndex:26040; }","duration":"175.676594ms","start":"2026-08-04T16:12:19.837621Z","end":"2026-08-04T16:12:20.013297Z","steps":["trace[1439678259] 'read index received' (duration: 175.497659ms)","trace[1439678259] 'applied index is now lower than readState.Index' (duration: 177.895µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:20.013428Z","caller":"traceutil/trace.go:171","msg":"trace[304334836] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"241.139287ms","start":"2026-08-04T16:12:19.772256Z","end":"2026-08-04T16:12:20.013395Z","steps":["trace[304334836] 'process raft request' (duration: 240.862379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.013609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.966273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-04T16:12:20.013659Z","caller":"traceutil/trace.go:171","msg":"trace[191093021] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25218; }","duration":"176.068716ms","start":"2026-08-04T16:12:19.837577Z","end":"2026-08-04T16:12:20.013646Z","steps":["trace[191093021] 'agreement among raft nodes before linearized reading' (duration: 175.864059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.013704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.461106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:20.013782Z","caller":"traceutil/trace.go:171","msg":"trace[955016742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25218; }","duration":"156.579889ms","start":"2026-08-04T16:12:19.85718Z","end":"2026-08-04T16:12:20.013759Z","steps":["trace[955016742] 'agreement among raft nodes before linearized reading' (duration: 156.463296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.01398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.107111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ecf0220-0f5b-4f91-b4e5-66ad4616f221\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-04T16:12:20.014016Z","caller":"traceutil/trace.go:171","msg":"trace[755555892] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5ecf0220-0f5b-4f91-b4e5-66ad4616f221; range_end:; response_count:1; response_revision:25218; }","duration":"109.224024ms","start":"2026-08-04T16:12:19.90478Z","end":"2026-08-04T16:12:20.014004Z","steps":["trace[755555892] 'agreement among raft nodes before linearized reading' (duration: 109.0869ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:20.56234Z","caller":"traceutil/trace.go:171","msg":"trace[844756333] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"265.746951ms","start":"2026-08-04T16:12:20.296497Z","end":"2026-08-04T16:12:20.562244Z","steps":["trace[844756333] 'process raft request' (duration: 265.599366ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:20.799336Z","caller":"traceutil/trace.go:171","msg":"trace[1485240052] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"427.80799ms","start":"2026-08-04T16:12:20.3715Z","end":"2026-08-04T16:12:20.799307Z","steps":["trace[1485240052] 'process raft request' (duration: 427.601734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.799497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:20.371478Z","time spent":"427.938324ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:20.799481Z","caller":"traceutil/trace.go:171","msg":"trace[473597976] linearizableReadLoop","detail":"{readStateIndex:26045; appliedIndex:26041; }","duration":"335.002084ms","start":"2026-08-04T16:12:20.464451Z","end":"2026-08-04T16:12:20.799453Z","steps":["trace[473597976] 'read index received' (duration: 97.582366ms)","trace[473597976] 'applied index is now lower than readState.Index' (duration: 237.417738ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:20.799549Z","caller":"traceutil/trace.go:171","msg":"trace[442847131] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"427.060188ms","start":"2026-08-04T16:12:20.372461Z","end":"2026-08-04T16:12:20.799521Z","steps":["trace[442847131] 'process raft request' (duration: 426.79191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.799774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.291422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ecf0220-0f5b-4f91-b4e5-66ad4616f221\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-04T16:12:20.799982Z","caller":"traceutil/trace.go:171","msg":"trace[987738216] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5ecf0220-0f5b-4f91-b4e5-66ad4616f221; range_end:; response_count:1; response_revision:25221; }","duration":"335.54812ms","start":"2026-08-04T16:12:20.464415Z","end":"2026-08-04T16:12:20.799963Z","steps":["trace[987738216] 'agreement among raft nodes before linearized reading' (duration: 335.176229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.799986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.985634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-04T16:12:20.799994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:20.372436Z","time spent":"427.45506ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T16:12:20.800029Z","caller":"traceutil/trace.go:171","msg":"trace[881915329] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25221; }","duration":"207.046046ms","start":"2026-08-04T16:12:20.592972Z","end":"2026-08-04T16:12:20.800018Z","steps":["trace[881915329] 'agreement among raft nodes before linearized reading' (duration: 206.950964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.800028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:20.464401Z","time spent":"335.616451ms","remote":"127.0.0.1:47206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5ecf0220-0f5b-4f91-b4e5-66ad4616f221\" "} {"level":"warn","ts":"2026-08-04T16:12:20.800449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.468835ms","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-08-04T16:12:20.800493Z","caller":"traceutil/trace.go:171","msg":"trace[1926998456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25221; }","duration":"190.513106ms","start":"2026-08-04T16:12:20.60997Z","end":"2026-08-04T16:12:20.800483Z","steps":["trace[1926998456] 'agreement among raft nodes before linearized reading' (duration: 190.380732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.80055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.864766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-04T16:12:20.800563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.704858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-04T16:12:20.800599Z","caller":"traceutil/trace.go:171","msg":"trace[251089674] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25221; }","duration":"215.78292ms","start":"2026-08-04T16:12:20.584808Z","end":"2026-08-04T16:12:20.800591Z","steps":["trace[251089674] 'agreement among raft nodes before linearized reading' (duration: 215.660046ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:20.800599Z","caller":"traceutil/trace.go:171","msg":"trace[328694635] 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:25221; }","duration":"263.946389ms","start":"2026-08-04T16:12:20.53664Z","end":"2026-08-04T16:12:20.800586Z","steps":["trace[328694635] 'agreement among raft nodes before linearized reading' (duration: 263.871477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.800573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.258763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-04T16:12:20.800689Z","caller":"traceutil/trace.go:171","msg":"trace[54084911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25221; }","duration":"216.405327ms","start":"2026-08-04T16:12:20.58427Z","end":"2026-08-04T16:12:20.800675Z","steps":["trace[54084911] 'agreement among raft nodes before linearized reading' (duration: 216.185271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.800922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.800075ms","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-08-04T16:12:20.800965Z","caller":"traceutil/trace.go:171","msg":"trace[1204855127] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25221; }","duration":"264.882837ms","start":"2026-08-04T16:12:20.53607Z","end":"2026-08-04T16:12:20.800953Z","steps":["trace[1204855127] 'agreement among raft nodes before linearized reading' (duration: 263.850497ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:20.907496Z","caller":"traceutil/trace.go:171","msg":"trace[688645154] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"101.229322ms","start":"2026-08-04T16:12:20.806242Z","end":"2026-08-04T16:12:20.907471Z","steps":["trace[688645154] 'process raft request' (duration: 80.247442ms)","trace[688645154] 'compare' (duration: 20.882487ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:20.9094Z","caller":"traceutil/trace.go:171","msg":"trace[1131545162] linearizableReadLoop","detail":"{readStateIndex:26048; appliedIndex:26045; }","duration":"100.722887ms","start":"2026-08-04T16:12:20.808654Z","end":"2026-08-04T16:12:20.909377Z","steps":["trace[1131545162] 'read index received' (duration: 77.851442ms)","trace[1131545162] 'applied index is now lower than readState.Index' (duration: 22.870425ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:20.909687Z","caller":"traceutil/trace.go:171","msg":"trace[1801627153] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"101.436938ms","start":"2026-08-04T16:12:20.808218Z","end":"2026-08-04T16:12:20.909655Z","steps":["trace[1801627153] 'process raft request' (duration: 101.093528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:20.909734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.045366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:20.909847Z","caller":"traceutil/trace.go:171","msg":"trace[899455124] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25224; }","duration":"101.180299ms","start":"2026-08-04T16:12:20.808638Z","end":"2026-08-04T16:12:20.909819Z","steps":["trace[899455124] 'agreement among raft nodes before linearized reading' (duration: 100.980684ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:20.909524Z","caller":"traceutil/trace.go:171","msg":"trace[1293537810] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"103.013423ms","start":"2026-08-04T16:12:20.806465Z","end":"2026-08-04T16:12:20.909478Z","steps":["trace[1293537810] 'process raft request' (duration: 102.683043ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:22.6914Z","caller":"traceutil/trace.go:171","msg":"trace[327658270] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"120.317166ms","start":"2026-08-04T16:12:22.571046Z","end":"2026-08-04T16:12:22.691363Z","steps":["trace[327658270] 'process raft request' (duration: 120.152371ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:22.9475Z","caller":"traceutil/trace.go:171","msg":"trace[853867037] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"142.609404ms","start":"2026-08-04T16:12:22.804851Z","end":"2026-08-04T16:12:22.94746Z","steps":["trace[853867037] 'process raft request' (duration: 97.216755ms)","trace[853867037] 'compare' (duration: 45.179013ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:22.950781Z","caller":"traceutil/trace.go:171","msg":"trace[577853608] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"143.53077ms","start":"2026-08-04T16:12:22.807211Z","end":"2026-08-04T16:12:22.950741Z","steps":["trace[577853608] 'process raft request' (duration: 143.283113ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:30.20204Z","caller":"traceutil/trace.go:171","msg":"trace[630000910] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"123.331023ms","start":"2026-08-04T16:12:30.078678Z","end":"2026-08-04T16:12:30.202009Z","steps":["trace[630000910] 'process raft request' (duration: 123.143518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:30.486758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.589347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:30.486842Z","caller":"traceutil/trace.go:171","msg":"trace[1220392871] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25324; }","duration":"223.730601ms","start":"2026-08-04T16:12:30.26309Z","end":"2026-08-04T16:12:30.48682Z","steps":["trace[1220392871] 'count revisions from in-memory index tree' (duration: 223.409781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:30.486978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.158331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:30.48705Z","caller":"traceutil/trace.go:171","msg":"trace[1452095149] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25324; }","duration":"163.275654ms","start":"2026-08-04T16:12:30.323752Z","end":"2026-08-04T16:12:30.487027Z","steps":["trace[1452095149] 'count revisions from in-memory index tree' (duration: 163.063827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:30.73647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.981803ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-08-04T16:12:30.858978Z","caller":"traceutil/trace.go:171","msg":"trace[802161760] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"118.766641ms","start":"2026-08-04T16:12:30.740187Z","end":"2026-08-04T16:12:30.858954Z","steps":["trace[802161760] 'process raft request' (duration: 118.548595ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:33.993862Z","caller":"traceutil/trace.go:171","msg":"trace[2099880943] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"101.731475ms","start":"2026-08-04T16:12:33.892097Z","end":"2026-08-04T16:12:33.993829Z","steps":["trace[2099880943] 'process raft request' (duration: 101.294413ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:35.502693Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006657.wal"} {"level":"info","ts":"2026-08-04T16:12:37.150015Z","caller":"traceutil/trace.go:171","msg":"trace[685602466] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"120.631545ms","start":"2026-08-04T16:12:37.029353Z","end":"2026-08-04T16:12:37.149985Z","steps":["trace[685602466] 'process raft request' (duration: 46.121339ms)","trace[685602466] 'compare' (duration: 74.359381ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:37.153929Z","caller":"traceutil/trace.go:171","msg":"trace[2085834361] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"124.150567ms","start":"2026-08-04T16:12:37.029751Z","end":"2026-08-04T16:12:37.153902Z","steps":["trace[2085834361] 'process raft request' (duration: 124.020804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:51.178902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.724091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:51.178967Z","caller":"traceutil/trace.go:171","msg":"trace[746475958] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25488; }","duration":"159.828654ms","start":"2026-08-04T16:12:51.019124Z","end":"2026-08-04T16:12:51.178953Z","steps":["trace[746475958] 'range keys from in-memory index tree' (duration: 159.665019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:51.179523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.344423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:12:51.179805Z","caller":"traceutil/trace.go:171","msg":"trace[277947475] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"134.791197ms","start":"2026-08-04T16:12:51.044991Z","end":"2026-08-04T16:12:51.179782Z","steps":["trace[277947475] 'process raft request' (duration: 134.700394ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:51.179878Z","caller":"traceutil/trace.go:171","msg":"trace[1647967809] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"139.475543ms","start":"2026-08-04T16:12:51.040376Z","end":"2026-08-04T16:12:51.179852Z","steps":["trace[1647967809] 'process raft request' (duration: 34.646916ms)","trace[1647967809] 'compare' (duration: 103.827077ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:51.367002Z","caller":"traceutil/trace.go:171","msg":"trace[2129780294] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"155.253022ms","start":"2026-08-04T16:12:51.211724Z","end":"2026-08-04T16:12:51.366977Z","steps":["trace[2129780294] 'process raft request' (duration: 118.7225ms)","trace[2129780294] 'compare' (duration: 36.340546ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:51.367324Z","caller":"traceutil/trace.go:171","msg":"trace[2085066198] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"154.261843ms","start":"2026-08-04T16:12:51.213035Z","end":"2026-08-04T16:12:51.367297Z","steps":["trace[2085066198] 'process raft request' (duration: 153.890342ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:51.367405Z","caller":"traceutil/trace.go:171","msg":"trace[371002568] linearizableReadLoop","detail":"{readStateIndex:26324; appliedIndex:26321; }","duration":"150.937866ms","start":"2026-08-04T16:12:51.216435Z","end":"2026-08-04T16:12:51.367372Z","steps":["trace[371002568] 'read index received' (duration: 113.999423ms)","trace[371002568] 'applied index is now lower than readState.Index' (duration: 36.936113ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:51.367431Z","caller":"traceutil/trace.go:171","msg":"trace[119672128] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"154.317604ms","start":"2026-08-04T16:12:51.213078Z","end":"2026-08-04T16:12:51.367396Z","steps":["trace[119672128] 'process raft request' (duration: 154.123598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:51.367584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.133131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-04T16:12:51.367639Z","caller":"traceutil/trace.go:171","msg":"trace[1338922027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25493; }","duration":"151.196743ms","start":"2026-08-04T16:12:51.216429Z","end":"2026-08-04T16:12:51.367626Z","steps":["trace[1338922027] 'agreement among raft nodes before linearized reading' (duration: 151.029968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:51.367655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.152692ms","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-08-04T16:12:51.367687Z","caller":"traceutil/trace.go:171","msg":"trace[858881595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25493; }","duration":"151.203934ms","start":"2026-08-04T16:12:51.216473Z","end":"2026-08-04T16:12:51.367676Z","steps":["trace[858881595] 'agreement among raft nodes before linearized reading' (duration: 151.035969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:51.367854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.888771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-04T16:12:51.367916Z","caller":"traceutil/trace.go:171","msg":"trace[2031222238] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25493; }","duration":"122.985943ms","start":"2026-08-04T16:12:51.244914Z","end":"2026-08-04T16:12:51.3679Z","steps":["trace[2031222238] 'agreement among raft nodes before linearized reading' (duration: 122.819058ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:51.521351Z","caller":"traceutil/trace.go:171","msg":"trace[1178838379] linearizableReadLoop","detail":"{readStateIndex:26327; appliedIndex:26324; }","duration":"137.32219ms","start":"2026-08-04T16:12:51.38401Z","end":"2026-08-04T16:12:51.521332Z","steps":["trace[1178838379] 'read index received' (duration: 48.280023ms)","trace[1178838379] 'applied index is now lower than readState.Index' (duration: 89.041607ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:51.521413Z","caller":"traceutil/trace.go:171","msg":"trace[1032537267] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"149.508094ms","start":"2026-08-04T16:12:51.371834Z","end":"2026-08-04T16:12:51.521343Z","steps":["trace[1032537267] 'process raft request' (duration: 97.373059ms)","trace[1032537267] 'compare' (duration: 51.95931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:51.521501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.482514ms","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-08-04T16:12:51.521817Z","caller":"traceutil/trace.go:171","msg":"trace[422006517] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25496; }","duration":"137.828774ms","start":"2026-08-04T16:12:51.383979Z","end":"2026-08-04T16:12:51.521808Z","steps":["trace[422006517] 'agreement among raft nodes before linearized reading' (duration: 137.427882ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:51.52153Z","caller":"traceutil/trace.go:171","msg":"trace[1160347893] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"149.386962ms","start":"2026-08-04T16:12:51.372131Z","end":"2026-08-04T16:12:51.521518Z","steps":["trace[1160347893] 'process raft request' (duration: 149.162345ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:51.732166Z","caller":"traceutil/trace.go:171","msg":"trace[196444474] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"117.074152ms","start":"2026-08-04T16:12:51.615047Z","end":"2026-08-04T16:12:51.732121Z","steps":["trace[196444474] 'process raft request' (duration: 88.585404ms)","trace[196444474] 'compare' (duration: 28.118737ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T16:12:51.960939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.501748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:12:51.96105Z","caller":"traceutil/trace.go:171","msg":"trace[1666153482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25499; }","duration":"102.663894ms","start":"2026-08-04T16:12:51.858358Z","end":"2026-08-04T16:12:51.961022Z","steps":["trace[1666153482] 'range keys from in-memory index tree' (duration: 102.400475ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.410727Z","caller":"traceutil/trace.go:171","msg":"trace[108408525] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"225.116751ms","start":"2026-08-04T16:12:53.185587Z","end":"2026-08-04T16:12:53.410704Z","steps":["trace[108408525] 'process raft request' (duration: 224.962027ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.537184Z","caller":"traceutil/trace.go:171","msg":"trace[2115001916] linearizableReadLoop","detail":"{readStateIndex:26334; appliedIndex:26332; }","duration":"304.509147ms","start":"2026-08-04T16:12:53.23266Z","end":"2026-08-04T16:12:53.537169Z","steps":["trace[2115001916] 'read index received' (duration: 177.785926ms)","trace[2115001916] 'applied index is now lower than readState.Index' (duration: 126.722231ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:53.537208Z","caller":"traceutil/trace.go:171","msg":"trace[1274603279] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"351.182893ms","start":"2026-08-04T16:12:53.185992Z","end":"2026-08-04T16:12:53.537175Z","steps":["trace[1274603279] 'process raft request' (duration: 351.003298ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.537225Z","caller":"traceutil/trace.go:171","msg":"trace[2049126077] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"165.129018ms","start":"2026-08-04T16:12:53.372061Z","end":"2026-08-04T16:12:53.53719Z","steps":["trace[2049126077] 'process raft request' (duration: 165.039325ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.537244Z","caller":"traceutil/trace.go:171","msg":"trace[1611685340] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"165.159799ms","start":"2026-08-04T16:12:53.37206Z","end":"2026-08-04T16:12:53.53722Z","steps":["trace[1611685340] 'process raft request' (duration: 165.063086ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.537259Z","caller":"traceutil/trace.go:171","msg":"trace[922827017] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"165.074706ms","start":"2026-08-04T16:12:53.372152Z","end":"2026-08-04T16:12:53.537227Z","steps":["trace[922827017] 'process raft request' (duration: 164.995494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:53.537316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.643311ms","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-08-04T16:12:53.53734Z","caller":"traceutil/trace.go:171","msg":"trace[1117739383] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:25506; }","duration":"304.722253ms","start":"2026-08-04T16:12:53.232611Z","end":"2026-08-04T16:12:53.537333Z","steps":["trace[1117739383] 'agreement among raft nodes before linearized reading' (duration: 304.649891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:53.537368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:53.232593Z","time spent":"304.769585ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"warn","ts":"2026-08-04T16:12:53.537354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T16:12:53.185966Z","time spent":"351.307507ms","remote":"127.0.0.1:47306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T16:12:53.537467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.609892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-04T16:12:53.537523Z","caller":"traceutil/trace.go:171","msg":"trace[391880360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25506; }","duration":"101.676834ms","start":"2026-08-04T16:12:53.435834Z","end":"2026-08-04T16:12:53.537511Z","steps":["trace[391880360] 'agreement among raft nodes before linearized reading' (duration: 101.486419ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.797471Z","caller":"traceutil/trace.go:171","msg":"trace[138232333] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"255.108762ms","start":"2026-08-04T16:12:53.542331Z","end":"2026-08-04T16:12:53.797439Z","steps":["trace[138232333] 'process raft request' (duration: 187.072625ms)","trace[138232333] 'compare' (duration: 67.862312ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:53.826345Z","caller":"traceutil/trace.go:171","msg":"trace[1888362138] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"283.979951ms","start":"2026-08-04T16:12:53.54233Z","end":"2026-08-04T16:12:53.82631Z","steps":["trace[1888362138] 'process raft request' (duration: 283.707793ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.826626Z","caller":"traceutil/trace.go:171","msg":"trace[422563052] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"238.891951ms","start":"2026-08-04T16:12:53.5877Z","end":"2026-08-04T16:12:53.826592Z","steps":["trace[422563052] 'process raft request' (duration: 238.775118ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.826656Z","caller":"traceutil/trace.go:171","msg":"trace[2064431361] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"212.488394ms","start":"2026-08-04T16:12:53.614132Z","end":"2026-08-04T16:12:53.82662Z","steps":["trace[2064431361] 'process raft request' (duration: 212.401482ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:12:53.826829Z","caller":"traceutil/trace.go:171","msg":"trace[281284374] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"282.167509ms","start":"2026-08-04T16:12:53.544648Z","end":"2026-08-04T16:12:53.826816Z","steps":["trace[281284374] 'process raft request' (duration: 281.604192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:12:54.676904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.921111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:12:54.677156Z","caller":"traceutil/trace.go:171","msg":"trace[1697142515] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"196.920691ms","start":"2026-08-04T16:12:54.480207Z","end":"2026-08-04T16:12:54.677128Z","steps":["trace[1697142515] 'process raft request' (duration: 72.710413ms)","trace[1697142515] 'compare' (duration: 123.695723ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:12:55.533613Z","caller":"traceutil/trace.go:171","msg":"trace[811149433] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"117.134633ms","start":"2026-08-04T16:12:55.416452Z","end":"2026-08-04T16:12:55.533587Z","steps":["trace[811149433] 'process raft request' (duration: 116.864025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:13:06.131648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.933798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:13:06.131806Z","caller":"traceutil/trace.go:171","msg":"trace[509877343] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"250.540829ms","start":"2026-08-04T16:13:05.881238Z","end":"2026-08-04T16:13:06.131778Z","steps":["trace[509877343] 'process raft request' (duration: 49.348753ms)","trace[509877343] 'compare' (duration: 200.534797ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:13:06.171423Z","caller":"traceutil/trace.go:171","msg":"trace[145390418] linearizableReadLoop","detail":"{readStateIndex:26435; appliedIndex:26433; }","duration":"286.442772ms","start":"2026-08-04T16:13:05.884888Z","end":"2026-08-04T16:13:06.171331Z","steps":["trace[145390418] 'read index received' (duration: 45.711918ms)","trace[145390418] 'applied index is now lower than readState.Index' (duration: 240.729744ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:13:06.171487Z","caller":"traceutil/trace.go:171","msg":"trace[99964118] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"289.098569ms","start":"2026-08-04T16:13:05.882349Z","end":"2026-08-04T16:13:06.171448Z","steps":["trace[99964118] 'process raft request' (duration: 288.726619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:13:06.171785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.826374ms","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-08-04T16:13:06.172412Z","caller":"traceutil/trace.go:171","msg":"trace[1780546689] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25601; }","duration":"287.515044ms","start":"2026-08-04T16:13:05.884871Z","end":"2026-08-04T16:13:06.172386Z","steps":["trace[1780546689] 'agreement among raft nodes before linearized reading' (duration: 286.631128ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:13:16.300128Z","caller":"traceutil/trace.go:171","msg":"trace[2025550372] transaction","detail":"{read_only:false; response_revision:25686; number_of_response:1; }","duration":"123.623902ms","start":"2026-08-04T16:13:16.176464Z","end":"2026-08-04T16:13:16.300088Z","steps":["trace[2025550372] 'process raft request' (duration: 123.399726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:13:16.574294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.722484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-04T16:13:16.574481Z","caller":"traceutil/trace.go:171","msg":"trace[2091544338] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"269.256803ms","start":"2026-08-04T16:13:16.305192Z","end":"2026-08-04T16:13:16.574448Z","steps":["trace[2091544338] 'process raft request' (duration: 122.084506ms)","trace[2091544338] 'compare' (duration: 146.357134ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T16:13:16.597607Z","caller":"traceutil/trace.go:171","msg":"trace[1578127989] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"292.324923ms","start":"2026-08-04T16:13:16.305258Z","end":"2026-08-04T16:13:16.597583Z","steps":["trace[1578127989] 'process raft request' (duration: 292.119277ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:13:16.597543Z","caller":"traceutil/trace.go:171","msg":"trace[1894941131] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"248.240263ms","start":"2026-08-04T16:13:16.349286Z","end":"2026-08-04T16:13:16.597526Z","steps":["trace[1894941131] 'process raft request' (duration: 248.202802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:13:16.996244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.094869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:13:16.996334Z","caller":"traceutil/trace.go:171","msg":"trace[1975719354] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25690; }","duration":"152.238703ms","start":"2026-08-04T16:13:16.844072Z","end":"2026-08-04T16:13:16.996311Z","steps":["trace[1975719354] 'count revisions from in-memory index tree' (duration: 152.002296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:13:17.437277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.73546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:13:17.437369Z","caller":"traceutil/trace.go:171","msg":"trace[2040884139] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25691; }","duration":"139.856034ms","start":"2026-08-04T16:13:17.297493Z","end":"2026-08-04T16:13:17.437349Z","steps":["trace[2040884139] 'range keys from in-memory index tree' (duration: 139.649498ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:13:17.830158Z","caller":"traceutil/trace.go:171","msg":"trace[154885681] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"212.236417ms","start":"2026-08-04T16:13:17.617897Z","end":"2026-08-04T16:13:17.830133Z","steps":["trace[154885681] 'process raft request' (duration: 212.032571ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:13:17.830467Z","caller":"traceutil/trace.go:171","msg":"trace[1001911429] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"209.089024ms","start":"2026-08-04T16:13:17.621335Z","end":"2026-08-04T16:13:17.830424Z","steps":["trace[1001911429] 'process raft request' (duration: 208.748154ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:13:17.830669Z","caller":"traceutil/trace.go:171","msg":"trace[610020375] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"208.090056ms","start":"2026-08-04T16:13:17.622555Z","end":"2026-08-04T16:13:17.830645Z","steps":["trace[610020375] 'process raft request' (duration: 207.775436ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:13:17.830756Z","caller":"traceutil/trace.go:171","msg":"trace[1405937964] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"182.723509ms","start":"2026-08-04T16:13:17.647996Z","end":"2026-08-04T16:13:17.830719Z","steps":["trace[1405937964] 'process raft request' (duration: 182.601635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T16:13:27.155803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.223927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-04T16:13:27.155874Z","caller":"traceutil/trace.go:171","msg":"trace[645378368] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25764; }","duration":"126.352951ms","start":"2026-08-04T16:13:27.029505Z","end":"2026-08-04T16:13:27.155858Z","steps":["trace[645378368] 'count revisions from in-memory index tree' (duration: 126.134335ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T16:13:31.901005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22939} {"level":"info","ts":"2026-08-04T16:13:32.045818Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22939,"took":"141.106171ms","hash":2385218381,"current-db-size-bytes":60841984,"current-db-size":"61 MB","current-db-size-in-use-bytes":36691968,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-04T16:13:32.045878Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2385218381,"revision":22939,"compact-revision":20048} {"level":"info","ts":"2026-08-04T16:18:31.906945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25796} {"level":"info","ts":"2026-08-04T16:18:32.040147Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25796,"took":"127.634792ms","hash":3711786386,"current-db-size-bytes":61685760,"current-db-size":"62 MB","current-db-size-in-use-bytes":37695488,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-04T16:18:32.040235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3711786386,"revision":25796,"compact-revision":22939} {"level":"info","ts":"2026-08-04T16:19:27.070739Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-04T16:19:27.076192Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-04T16:19:27.076298Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-04T16:23:31.912129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28674} {"level":"info","ts":"2026-08-04T16:23:32.052538Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28674,"took":"136.815813ms","hash":2860980645,"current-db-size-bytes":61685760,"current-db-size":"62 MB","current-db-size-in-use-bytes":30834688,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-04T16:23:32.052615Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2860980645,"revision":28674,"compact-revision":25796} {"level":"info","ts":"2026-08-04T16:28:31.918231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30892} {"level":"info","ts":"2026-08-04T16:28:32.033135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30892,"took":"110.818029ms","hash":4178996140,"current-db-size-bytes":61685760,"current-db-size":"62 MB","current-db-size-in-use-bytes":31199232,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-04T16:28:32.033203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4178996140,"revision":30892,"compact-revision":28674} {"level":"info","ts":"2026-08-04T16:33:31.924966Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33160} {"level":"info","ts":"2026-08-04T16:33:32.081293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33160,"took":"152.863731ms","hash":3110592144,"current-db-size-bytes":62730240,"current-db-size":"63 MB","current-db-size-in-use-bytes":30117888,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-04T16:33:32.081391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3110592144,"revision":33160,"compact-revision":30892}