{"level":"warn","ts":"2026-05-08T18:19:25.001308Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:19:25.001403Z","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-05-08T18:19:25.001474Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:19:25.001487Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-05-08T18:19:25.001504Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T18:19:25.002434Z","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-05-08T18:19:25.00254Z","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-05-08T18:19:25.006945Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.123533ms"} {"level":"info","ts":"2026-05-08T18:19:25.013331Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-05-08T18:19:25.013454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T18:19:25.013493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-05-08T18:19:25.013513Z","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-05-08T18:19:25.01353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-05-08T18:19:25.013585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-05-08T18:19:25.02003Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T18:19:25.021917Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T18:19:25.024082Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T18:19:25.02826Z","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-05-08T18:19:25.028833Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:19:25.028977Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:19:25.028839Z","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-05-08T18:19:25.029015Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:19:25.031168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-05-08T18:19:25.031441Z","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-05-08T18:19:25.032868Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T18:19:25.033031Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-08T18:19:25.033079Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-05-08T18:19:25.033221Z","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-05-08T18:19:25.033275Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T18:19:25.914236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T18:19:25.914299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T18:19:25.914348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-05-08T18:19:25.914367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-05-08T18:19:25.914386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-08T18:19:25.914402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-05-08T18:19:25.914415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-05-08T18:19:25.922757Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:19:25.939Z","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-05-08T18:19:25.939124Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:19:25.9392Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:19:25.941351Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T18:19:25.941552Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T18:19:25.941571Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T18:19:25.941684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-05-08T18:19:25.949445Z","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-05-08T18:19:25.949614Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:19:25.94966Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:19:42.086223Z","caller":"traceutil/trace.go:171","msg":"trace[1020297890] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"152.148395ms","start":"2026-05-08T18:19:41.934026Z","end":"2026-05-08T18:19:42.086175Z","steps":["trace[1020297890] 'process raft request' (duration: 151.991181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:42.422872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.720949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-08T18:19:42.423004Z","caller":"traceutil/trace.go:171","msg":"trace[304510275] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:414; }","duration":"228.908063ms","start":"2026-05-08T18:19:42.194078Z","end":"2026-05-08T18:19:42.422986Z","steps":["trace[304510275] 'range keys from in-memory index tree' (duration: 228.595006ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:56.751555Z","caller":"traceutil/trace.go:171","msg":"trace[1224417872] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"109.436948ms","start":"2026-05-08T18:19:56.642098Z","end":"2026-05-08T18:19:56.751535Z","steps":["trace[1224417872] 'process raft request' (duration: 109.312165ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:56.765276Z","caller":"traceutil/trace.go:171","msg":"trace[2102731013] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"115.535779ms","start":"2026-05-08T18:19:56.649722Z","end":"2026-05-08T18:19:56.765258Z","steps":["trace[2102731013] 'process raft request' (duration: 115.396526ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:56.99366Z","caller":"traceutil/trace.go:171","msg":"trace[1827065264] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"169.944125ms","start":"2026-05-08T18:19:56.823683Z","end":"2026-05-08T18:19:56.993627Z","steps":["trace[1827065264] 'process raft request' (duration: 169.695508ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:57.000596Z","caller":"traceutil/trace.go:171","msg":"trace[1535403046] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"170.116889ms","start":"2026-05-08T18:19:56.830391Z","end":"2026-05-08T18:19:57.000508Z","steps":["trace[1535403046] 'process raft request' (duration: 169.885854ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:03.090491Z","caller":"traceutil/trace.go:171","msg":"trace[1360070858] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"131.361757ms","start":"2026-05-08T18:20:02.959106Z","end":"2026-05-08T18:20:03.090467Z","steps":["trace[1360070858] 'read index received' (duration: 130.637568ms)","trace[1360070858] 'applied index is now lower than readState.Index' (duration: 722.599µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:03.090553Z","caller":"traceutil/trace.go:171","msg":"trace[1106472185] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"215.149626ms","start":"2026-05-08T18:20:02.875379Z","end":"2026-05-08T18:20:03.090528Z","steps":["trace[1106472185] 'process raft request' (duration: 214.633993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:03.090673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.550112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mj5w6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T18:20:03.09071Z","caller":"traceutil/trace.go:171","msg":"trace[1363459825] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mj5w6; range_end:; response_count:1; response_revision:623; }","duration":"131.637405ms","start":"2026-05-08T18:20:02.959062Z","end":"2026-05-08T18:20:03.090699Z","steps":["trace[1363459825] 'agreement among raft nodes before linearized reading' (duration: 131.501841ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:03.314013Z","caller":"traceutil/trace.go:171","msg":"trace[591801556] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"210.937532ms","start":"2026-05-08T18:20:03.103042Z","end":"2026-05-08T18:20:03.313979Z","steps":["trace[591801556] 'process raft request' (duration: 176.64606ms)","trace[591801556] 'compare' (duration: 33.950354ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:03.31405Z","caller":"traceutil/trace.go:171","msg":"trace[395308457] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"165.26386ms","start":"2026-05-08T18:20:03.148743Z","end":"2026-05-08T18:20:03.314007Z","steps":["trace[395308457] 'process raft request' (duration: 165.177668ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:03.512092Z","caller":"traceutil/trace.go:171","msg":"trace[1994529664] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"148.773455ms","start":"2026-05-08T18:20:03.363287Z","end":"2026-05-08T18:20:03.51206Z","steps":["trace[1994529664] 'process raft request' (duration: 65.065498ms)","trace[1994529664] 'compare' (duration: 83.405849ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:03.512296Z","caller":"traceutil/trace.go:171","msg":"trace[1155803883] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"148.698602ms","start":"2026-05-08T18:20:03.363436Z","end":"2026-05-08T18:20:03.512135Z","steps":["trace[1155803883] 'process raft request' (duration: 148.535418ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:03.512415Z","caller":"traceutil/trace.go:171","msg":"trace[1056323482] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"148.690283ms","start":"2026-05-08T18:20:03.363678Z","end":"2026-05-08T18:20:03.512368Z","steps":["trace[1056323482] 'process raft request' (duration: 148.373755ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:04.05216Z","caller":"traceutil/trace.go:171","msg":"trace[657307521] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"450.220511ms","start":"2026-05-08T18:20:03.601916Z","end":"2026-05-08T18:20:04.052137Z","steps":["trace[657307521] 'read index received' (duration: 449.970965ms)","trace[657307521] 'applied index is now lower than readState.Index' (duration: 248.196µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:20:04.052286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.359144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:20:04.052256Z","caller":"traceutil/trace.go:171","msg":"trace[531302015] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"535.402513ms","start":"2026-05-08T18:20:03.516816Z","end":"2026-05-08T18:20:04.052219Z","steps":["trace[531302015] 'process raft request' (duration: 535.162537ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:04.052318Z","caller":"traceutil/trace.go:171","msg":"trace[758843512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"450.442416ms","start":"2026-05-08T18:20:03.601866Z","end":"2026-05-08T18:20:04.052308Z","steps":["trace[758843512] 'agreement among raft nodes before linearized reading' (duration: 450.371034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:04.052351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:20:03.601845Z","time spent":"450.499468ms","remote":"127.0.0.1:45302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T18:20:04.052869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:20:03.5168Z","time spent":"535.543206ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:20:20.209439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dbzwj\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-08T18:20:20.20958Z","caller":"traceutil/trace.go:171","msg":"trace[1909926433] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dbzwj; range_end:; response_count:1; response_revision:789; }","duration":"148.757204ms","start":"2026-05-08T18:20:20.060805Z","end":"2026-05-08T18:20:20.209562Z","steps":["trace[1909926433] 'range keys from in-memory index tree' (duration: 148.397856ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:47.470451Z","caller":"traceutil/trace.go:171","msg":"trace[1709006878] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"188.942113ms","start":"2026-05-08T18:20:47.281491Z","end":"2026-05-08T18:20:47.470433Z","steps":["trace[1709006878] 'process raft request' (duration: 188.82671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:21:33.094346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.303229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:21:33.094467Z","caller":"traceutil/trace.go:171","msg":"trace[1116339869] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"304.42985ms","start":"2026-05-08T18:21:32.79001Z","end":"2026-05-08T18:21:33.09444Z","steps":["trace[1116339869] 'process raft request' (duration: 47.851876ms)","trace[1116339869] 'compare' (duration: 256.206735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:21:33.094604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:21:32.79Z","time spent":"304.526903ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:21:33.167101Z","caller":"traceutil/trace.go:171","msg":"trace[1030372128] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"117.218583ms","start":"2026-05-08T18:21:33.049841Z","end":"2026-05-08T18:21:33.16706Z","steps":["trace[1030372128] 'process raft request' (duration: 117.001338ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:21:33.603115Z","caller":"traceutil/trace.go:171","msg":"trace[713362535] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"173.419645ms","start":"2026-05-08T18:21:33.429609Z","end":"2026-05-08T18:21:33.603029Z","steps":["trace[713362535] 'process raft request' (duration: 173.125437ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:18.980859Z","caller":"traceutil/trace.go:171","msg":"trace[1239080446] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"168.012594ms","start":"2026-05-08T18:22:18.812795Z","end":"2026-05-08T18:22:18.980808Z","steps":["trace[1239080446] 'process raft request' (duration: 167.723297ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:22:19.164194Z","caller":"traceutil/trace.go:171","msg":"trace[1760543709] linearizableReadLoop","detail":"{readStateIndex:2219; appliedIndex:2218; }","duration":"118.741005ms","start":"2026-05-08T18:22:19.045404Z","end":"2026-05-08T18:22:19.164145Z","steps":["trace[1760543709] 'read index received' (duration: 92.193685ms)","trace[1760543709] 'applied index is now lower than readState.Index' (duration: 26.54494ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:22:19.164217Z","caller":"traceutil/trace.go:171","msg":"trace[1237428185] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"161.717461ms","start":"2026-05-08T18:22:19.002451Z","end":"2026-05-08T18:22:19.164169Z","steps":["trace[1237428185] 'process raft request' (duration: 135.232253ms)","trace[1237428185] 'compare' (duration: 26.211682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:22:19.164529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.115193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T18:22:19.164585Z","caller":"traceutil/trace.go:171","msg":"trace[1109164153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2142; }","duration":"119.229926ms","start":"2026-05-08T18:22:19.045343Z","end":"2026-05-08T18:22:19.164573Z","steps":["trace[1109164153] 'agreement among raft nodes before linearized reading' (duration: 118.96319ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:23:08.838952Z","caller":"traceutil/trace.go:171","msg":"trace[354866548] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"111.675565ms","start":"2026-05-08T18:23:08.727251Z","end":"2026-05-08T18:23:08.838926Z","steps":["trace[354866548] 'process raft request' (duration: 111.548072ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:27:39.362174Z","caller":"traceutil/trace.go:171","msg":"trace[107276678] linearizableReadLoop","detail":"{readStateIndex:4899; appliedIndex:4897; }","duration":"105.321914ms","start":"2026-05-08T18:27:39.25683Z","end":"2026-05-08T18:27:39.362152Z","steps":["trace[107276678] 'read index received' (duration: 24.477353ms)","trace[107276678] 'applied index is now lower than readState.Index' (duration: 80.843751ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:27:39.362372Z","caller":"traceutil/trace.go:171","msg":"trace[1311357399] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"107.617388ms","start":"2026-05-08T18:27:39.254744Z","end":"2026-05-08T18:27:39.362361Z","steps":["trace[1311357399] 'process raft request' (duration: 92.899553ms)","trace[1311357399] 'compare' (duration: 14.418008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:27:39.362622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.770514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-08T18:27:39.362701Z","caller":"traceutil/trace.go:171","msg":"trace[1580482525] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4721; }","duration":"105.905117ms","start":"2026-05-08T18:27:39.256777Z","end":"2026-05-08T18:27:39.362682Z","steps":["trace[1580482525] 'agreement among raft nodes before linearized reading' (duration: 105.59908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:27:39.367565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.376158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-05-08T18:27:39.367654Z","caller":"traceutil/trace.go:171","msg":"trace[541371773] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4722; }","duration":"109.506901ms","start":"2026-05-08T18:27:39.258125Z","end":"2026-05-08T18:27:39.367632Z","steps":["trace[541371773] 'agreement among raft nodes before linearized reading' (duration: 109.221885ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:06.578457Z","caller":"traceutil/trace.go:171","msg":"trace[188061788] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"274.992212ms","start":"2026-05-08T18:28:06.303438Z","end":"2026-05-08T18:28:06.57843Z","steps":["trace[188061788] 'process raft request' (duration: 274.778497ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:06.60056Z","caller":"traceutil/trace.go:171","msg":"trace[1132613222] linearizableReadLoop","detail":"{readStateIndex:5590; appliedIndex:5588; }","duration":"244.749865ms","start":"2026-05-08T18:28:06.355788Z","end":"2026-05-08T18:28:06.600538Z","steps":["trace[1132613222] 'read index received' (duration: 222.468434ms)","trace[1132613222] 'applied index is now lower than readState.Index' (duration: 22.280631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:28:06.600753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.955599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T18:28:06.600786Z","caller":"traceutil/trace.go:171","msg":"trace[1796162416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5392; }","duration":"245.063253ms","start":"2026-05-08T18:28:06.355713Z","end":"2026-05-08T18:28:06.600777Z","steps":["trace[1796162416] 'agreement among raft nodes before linearized reading' (duration: 244.928649ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:06.600924Z","caller":"traceutil/trace.go:171","msg":"trace[1366754011] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"296.394533ms","start":"2026-05-08T18:28:06.304447Z","end":"2026-05-08T18:28:06.600842Z","steps":["trace[1366754011] 'process raft request' (duration: 295.954973ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:06.727807Z","caller":"traceutil/trace.go:171","msg":"trace[1625772271] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"122.378422ms","start":"2026-05-08T18:28:06.605393Z","end":"2026-05-08T18:28:06.727771Z","steps":["trace[1625772271] 'process raft request' (duration: 96.20908ms)","trace[1625772271] 'compare' (duration: 26.009758ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:28:06.733374Z","caller":"traceutil/trace.go:171","msg":"trace[1520880521] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"127.652575ms","start":"2026-05-08T18:28:06.605694Z","end":"2026-05-08T18:28:06.733346Z","steps":["trace[1520880521] 'process raft request' (duration: 127.479931ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:06.733705Z","caller":"traceutil/trace.go:171","msg":"trace[1236022963] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"124.986953ms","start":"2026-05-08T18:28:06.608664Z","end":"2026-05-08T18:28:06.733651Z","steps":["trace[1236022963] 'process raft request' (duration: 124.625315ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:17.331654Z","caller":"traceutil/trace.go:171","msg":"trace[137350185] linearizableReadLoop","detail":"{readStateIndex:5753; appliedIndex:5752; }","duration":"137.365624ms","start":"2026-05-08T18:28:17.194263Z","end":"2026-05-08T18:28:17.331629Z","steps":["trace[137350185] 'read index received' (duration: 137.199789ms)","trace[137350185] 'applied index is now lower than readState.Index' (duration: 164.505µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:28:17.331923Z","caller":"traceutil/trace.go:171","msg":"trace[1772794579] transaction","detail":"{read_only:false; response_revision:5552; number_of_response:1; }","duration":"152.215571ms","start":"2026-05-08T18:28:17.179671Z","end":"2026-05-08T18:28:17.331887Z","steps":["trace[1772794579] 'process raft request' (duration: 151.78145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:28:17.332145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.830744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T18:28:17.332261Z","caller":"traceutil/trace.go:171","msg":"trace[2020333631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5552; }","duration":"138.020849ms","start":"2026-05-08T18:28:17.194214Z","end":"2026-05-08T18:28:17.332235Z","steps":["trace[2020333631] 'agreement among raft nodes before linearized reading' (duration: 137.690481ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:17.34741Z","caller":"traceutil/trace.go:171","msg":"trace[1828286430] transaction","detail":"{read_only:false; response_revision:5553; number_of_response:1; }","duration":"115.489881ms","start":"2026-05-08T18:28:17.231894Z","end":"2026-05-08T18:28:17.347384Z","steps":["trace[1828286430] 'process raft request' (duration: 114.359035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:28:17.347506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.978947ms","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-05-08T18:28:17.347558Z","caller":"traceutil/trace.go:171","msg":"trace[859499489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5553; }","duration":"150.06402ms","start":"2026-05-08T18:28:17.197482Z","end":"2026-05-08T18:28:17.347546Z","steps":["trace[859499489] 'agreement among raft nodes before linearized reading' (duration: 149.868515ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:18.008973Z","caller":"traceutil/trace.go:171","msg":"trace[66997291] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"222.104205ms","start":"2026-05-08T18:28:17.786828Z","end":"2026-05-08T18:28:18.008932Z","steps":["trace[66997291] 'process raft request' (duration: 221.921881ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:18.009064Z","caller":"traceutil/trace.go:171","msg":"trace[574696099] transaction","detail":"{read_only:false; response_revision:5559; number_of_response:1; }","duration":"222.596967ms","start":"2026-05-08T18:28:17.78642Z","end":"2026-05-08T18:28:18.009017Z","steps":["trace[574696099] 'process raft request' (duration: 179.238573ms)","trace[574696099] 'compare' (duration: 42.845432ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:28:18.009143Z","caller":"traceutil/trace.go:171","msg":"trace[1255247309] linearizableReadLoop","detail":"{readStateIndex:5762; appliedIndex:5759; }","duration":"175.691019ms","start":"2026-05-08T18:28:17.833428Z","end":"2026-05-08T18:28:18.009119Z","steps":["trace[1255247309] 'read index received' (duration: 132.292204ms)","trace[1255247309] 'applied index is now lower than readState.Index' (duration: 43.397855ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:28:18.009277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.821402ms","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:5"} {"level":"info","ts":"2026-05-08T18:28:18.009338Z","caller":"traceutil/trace.go:171","msg":"trace[126094740] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:5561; }","duration":"175.927734ms","start":"2026-05-08T18:28:17.833389Z","end":"2026-05-08T18:28:18.009316Z","steps":["trace[126094740] 'agreement among raft nodes before linearized reading' (duration: 175.820331ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:18.009316Z","caller":"traceutil/trace.go:171","msg":"trace[177033852] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"222.423942ms","start":"2026-05-08T18:28:17.786866Z","end":"2026-05-08T18:28:18.00929Z","steps":["trace[177033852] 'process raft request' (duration: 221.982412ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:18.659035Z","caller":"traceutil/trace.go:171","msg":"trace[131548887] transaction","detail":"{read_only:false; response_revision:5562; number_of_response:1; }","duration":"546.578635ms","start":"2026-05-08T18:28:18.112446Z","end":"2026-05-08T18:28:18.659025Z","steps":["trace[131548887] 'process raft request' (duration: 546.400011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:28:18.659162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:28:18.112427Z","time spent":"546.669327ms","remote":"127.0.0.1:39096","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-05-08T18:28:18.659262Z","caller":"traceutil/trace.go:171","msg":"trace[2016959923] linearizableReadLoop","detail":"{readStateIndex:5763; appliedIndex:5762; }","duration":"121.386939ms","start":"2026-05-08T18:28:18.537597Z","end":"2026-05-08T18:28:18.658984Z","steps":["trace[2016959923] 'read index received' (duration: 121.172634ms)","trace[2016959923] 'applied index is now lower than readState.Index' (duration: 213.725µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:28:18.659365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.716746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:28:18.659452Z","caller":"traceutil/trace.go:171","msg":"trace[1206067414] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5562; }","duration":"121.874141ms","start":"2026-05-08T18:28:18.537556Z","end":"2026-05-08T18:28:18.659431Z","steps":["trace[1206067414] 'agreement among raft nodes before linearized reading' (duration: 121.633065ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:28:18.808084Z","caller":"traceutil/trace.go:171","msg":"trace[1974490935] linearizableReadLoop","detail":"{readStateIndex:5764; appliedIndex:5763; }","duration":"112.093892ms","start":"2026-05-08T18:28:18.69597Z","end":"2026-05-08T18:28:18.808064Z","steps":["trace[1974490935] 'read index received' (duration: 43.927027ms)","trace[1974490935] 'applied index is now lower than readState.Index' (duration: 68.165955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:28:18.80833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.334587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-08T18:28:18.808407Z","caller":"traceutil/trace.go:171","msg":"trace[778613049] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"142.927683ms","start":"2026-05-08T18:28:18.665446Z","end":"2026-05-08T18:28:18.808373Z","steps":["trace[778613049] 'process raft request' (duration: 74.448611ms)","trace[778613049] 'compare' (duration: 67.931538ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:28:18.808424Z","caller":"traceutil/trace.go:171","msg":"trace[996149896] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5563; }","duration":"112.47084ms","start":"2026-05-08T18:28:18.695937Z","end":"2026-05-08T18:28:18.808408Z","steps":["trace[996149896] 'agreement among raft nodes before linearized reading' (duration: 112.249035ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:29:25.968261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3027} {"level":"info","ts":"2026-05-08T18:29:26.05443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3027,"took":"84.812378ms","hash":4146748413,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T18:29:26.054509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4146748413,"revision":3027,"compact-revision":-1} {"level":"warn","ts":"2026-05-08T18:34:10.965415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.42513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-05-08T18:34:10.965832Z","caller":"traceutil/trace.go:171","msg":"trace[1283638569] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8220; }","duration":"109.590004ms","start":"2026-05-08T18:34:10.855922Z","end":"2026-05-08T18:34:10.965512Z","steps":["trace[1283638569] 'range keys from in-memory index tree' (duration: 109.016141ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:11.794177Z","caller":"traceutil/trace.go:171","msg":"trace[313222767] linearizableReadLoop","detail":"{readStateIndex:8511; appliedIndex:8510; }","duration":"190.968008ms","start":"2026-05-08T18:34:11.603186Z","end":"2026-05-08T18:34:11.794154Z","steps":["trace[313222767] 'read index received' (duration: 190.758043ms)","trace[313222767] 'applied index is now lower than readState.Index' (duration: 208.665µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:11.79424Z","caller":"traceutil/trace.go:171","msg":"trace[1288950379] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"219.601487ms","start":"2026-05-08T18:34:11.57459Z","end":"2026-05-08T18:34:11.794192Z","steps":["trace[1288950379] 'process raft request' (duration: 219.369832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:11.794302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.09619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:34:11.794337Z","caller":"traceutil/trace.go:171","msg":"trace[944493750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8224; }","duration":"191.187513ms","start":"2026-05-08T18:34:11.60314Z","end":"2026-05-08T18:34:11.794327Z","steps":["trace[944493750] 'agreement among raft nodes before linearized reading' (duration: 191.105131ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:12.614364Z","caller":"traceutil/trace.go:171","msg":"trace[1602089279] transaction","detail":"{read_only:false; response_revision:8226; number_of_response:1; }","duration":"117.150474ms","start":"2026-05-08T18:34:12.497187Z","end":"2026-05-08T18:34:12.614337Z","steps":["trace[1602089279] 'process raft request' (duration: 116.9572ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:12.714635Z","caller":"traceutil/trace.go:171","msg":"trace[1793507437] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"115.581617ms","start":"2026-05-08T18:34:12.599026Z","end":"2026-05-08T18:34:12.714608Z","steps":["trace[1793507437] 'process raft request' (duration: 100.229342ms)","trace[1793507437] 'compare' (duration: 15.246533ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:12.718632Z","caller":"traceutil/trace.go:171","msg":"trace[1314412229] transaction","detail":"{read_only:false; response_revision:8228; number_of_response:1; }","duration":"101.627895ms","start":"2026-05-08T18:34:12.616988Z","end":"2026-05-08T18:34:12.718616Z","steps":["trace[1314412229] 'process raft request' (duration: 101.553243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:12.940799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.861682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-08T18:34:12.94091Z","caller":"traceutil/trace.go:171","msg":"trace[194773446] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8229; }","duration":"120.992755ms","start":"2026-05-08T18:34:12.81987Z","end":"2026-05-08T18:34:12.940863Z","steps":["trace[194773446] 'range keys from in-memory index tree' (duration: 120.659548ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:25.974781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6135} {"level":"info","ts":"2026-05-08T18:34:26.073072Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6135,"took":"95.671313ms","hash":1623181342,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":23126016,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-08T18:34:26.073145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1623181342,"revision":6135,"compact-revision":3027} {"level":"info","ts":"2026-05-08T18:34:39.838351Z","caller":"traceutil/trace.go:171","msg":"trace[1355354034] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8727; }","duration":"130.614874ms","start":"2026-05-08T18:34:39.707715Z","end":"2026-05-08T18:34:39.83833Z","steps":["trace[1355354034] 'read index received' (duration: 130.533252ms)","trace[1355354034] 'applied index is now lower than readState.Index' (duration: 80.922µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:39.838556Z","caller":"traceutil/trace.go:171","msg":"trace[609451357] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"133.79991ms","start":"2026-05-08T18:34:39.704745Z","end":"2026-05-08T18:34:39.838544Z","steps":["trace[609451357] 'process raft request' (duration: 133.394201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:39.838994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.260939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wtfcn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-08T18:34:39.83904Z","caller":"traceutil/trace.go:171","msg":"trace[134341094] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wtfcn; range_end:; response_count:1; response_revision:8431; }","duration":"131.343121ms","start":"2026-05-08T18:34:39.707685Z","end":"2026-05-08T18:34:39.839028Z","steps":["trace[134341094] 'agreement among raft nodes before linearized reading' (duration: 130.741377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:52.906444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.227175ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:34:52.90659Z","caller":"traceutil/trace.go:171","msg":"trace[1886535415] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8536; }","duration":"154.388778ms","start":"2026-05-08T18:34:52.752183Z","end":"2026-05-08T18:34:52.906572Z","steps":["trace[1886535415] 'range keys from in-memory index tree' (duration: 154.209724ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:52.908301Z","caller":"traceutil/trace.go:171","msg":"trace[826875514] linearizableReadLoop","detail":"{readStateIndex:8838; appliedIndex:8837; }","duration":"241.54779ms","start":"2026-05-08T18:34:52.666722Z","end":"2026-05-08T18:34:52.90827Z","steps":["trace[826875514] 'read index received' (duration: 241.261822ms)","trace[826875514] 'applied index is now lower than readState.Index' (duration: 284.698µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:52.908793Z","caller":"traceutil/trace.go:171","msg":"trace[191528399] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"275.982559ms","start":"2026-05-08T18:34:52.632789Z","end":"2026-05-08T18:34:52.908772Z","steps":["trace[191528399] 'process raft request' (duration: 275.301652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:52.909142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.420951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19383"} {"level":"info","ts":"2026-05-08T18:34:52.909196Z","caller":"traceutil/trace.go:171","msg":"trace[161204802] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8537; }","duration":"242.519654ms","start":"2026-05-08T18:34:52.666663Z","end":"2026-05-08T18:34:52.909183Z","steps":["trace[161204802] 'agreement among raft nodes before linearized reading' (duration: 242.328169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:52.917241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.341322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:34:52.917315Z","caller":"traceutil/trace.go:171","msg":"trace[453664456] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8540; }","duration":"102.484705ms","start":"2026-05-08T18:34:52.814812Z","end":"2026-05-08T18:34:52.917296Z","steps":["trace[453664456] 'agreement among raft nodes before linearized reading' (duration: 102.378823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:52.917365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.993986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-08T18:34:52.917426Z","caller":"traceutil/trace.go:171","msg":"trace[1160121624] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8540; }","duration":"177.101518ms","start":"2026-05-08T18:34:52.740315Z","end":"2026-05-08T18:34:52.917416Z","steps":["trace[1160121624] 'agreement among raft nodes before linearized reading' (duration: 177.010696ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:53.458528Z","caller":"traceutil/trace.go:171","msg":"trace[119918184] linearizableReadLoop","detail":"{readStateIndex:8847; appliedIndex:8845; }","duration":"101.450811ms","start":"2026-05-08T18:34:53.357053Z","end":"2026-05-08T18:34:53.458504Z","steps":["trace[119918184] 'read index received' (duration: 38.667099ms)","trace[119918184] 'applied index is now lower than readState.Index' (duration: 62.782852ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:53.458613Z","caller":"traceutil/trace.go:171","msg":"trace[897490793] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"103.401008ms","start":"2026-05-08T18:34:53.355186Z","end":"2026-05-08T18:34:53.458587Z","steps":["trace[897490793] 'process raft request' (duration: 103.184642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:53.458759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.696856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-08T18:34:53.45884Z","caller":"traceutil/trace.go:171","msg":"trace[156962856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8546; }","duration":"101.803999ms","start":"2026-05-08T18:34:53.356984Z","end":"2026-05-08T18:34:53.458788Z","steps":["trace[156962856] 'agreement among raft nodes before linearized reading' (duration: 101.612125ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:53.716632Z","caller":"traceutil/trace.go:171","msg":"trace[112179570] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"100.235122ms","start":"2026-05-08T18:34:53.616376Z","end":"2026-05-08T18:34:53.716611Z","steps":["trace[112179570] 'process raft request' (duration: 100.1488ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:53.717184Z","caller":"traceutil/trace.go:171","msg":"trace[1919903538] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"252.95681ms","start":"2026-05-08T18:34:53.464199Z","end":"2026-05-08T18:34:53.717156Z","steps":["trace[1919903538] 'process raft request' (duration: 216.239948ms)","trace[1919903538] 'compare' (duration: 35.831912ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:53.717196Z","caller":"traceutil/trace.go:171","msg":"trace[700972140] linearizableReadLoop","detail":"{readStateIndex:8848; appliedIndex:8847; }","duration":"130.46711ms","start":"2026-05-08T18:34:53.586703Z","end":"2026-05-08T18:34:53.71717Z","steps":["trace[700972140] 'read index received' (duration: 93.701796ms)","trace[700972140] 'applied index is now lower than readState.Index' (duration: 36.763944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:34:53.717378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.659014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:34:53.717452Z","caller":"traceutil/trace.go:171","msg":"trace[503436201] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:8548; }","duration":"130.814068ms","start":"2026-05-08T18:34:53.586623Z","end":"2026-05-08T18:34:53.717437Z","steps":["trace[503436201] 'agreement among raft nodes before linearized reading' (duration: 130.637904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:53.717466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.397587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-05-08T18:34:53.717528Z","caller":"traceutil/trace.go:171","msg":"trace[376632127] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8548; }","duration":"127.488659ms","start":"2026-05-08T18:34:53.590026Z","end":"2026-05-08T18:34:53.717515Z","steps":["trace[376632127] 'agreement among raft nodes before linearized reading' (duration: 127.307344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:53.71752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.036249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:34:53.717618Z","caller":"traceutil/trace.go:171","msg":"trace[73421178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8548; }","duration":"114.207793ms","start":"2026-05-08T18:34:53.603389Z","end":"2026-05-08T18:34:53.717597Z","steps":["trace[73421178] 'agreement among raft nodes before linearized reading' (duration: 113.943796ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:54.339862Z","caller":"traceutil/trace.go:171","msg":"trace[531355395] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"180.545151ms","start":"2026-05-08T18:34:54.159293Z","end":"2026-05-08T18:34:54.339838Z","steps":["trace[531355395] 'process raft request' (duration: 180.333445ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:34:54.407443Z","caller":"traceutil/trace.go:171","msg":"trace[49291721] linearizableReadLoop","detail":"{readStateIndex:8853; appliedIndex:8851; }","duration":"211.623939ms","start":"2026-05-08T18:34:54.195789Z","end":"2026-05-08T18:34:54.407413Z","steps":["trace[49291721] 'read index received' (duration: 143.88924ms)","trace[49291721] 'applied index is now lower than readState.Index' (duration: 67.733099ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:54.407542Z","caller":"traceutil/trace.go:171","msg":"trace[1054061702] transaction","detail":"{read_only:false; response_revision:8552; number_of_response:1; }","duration":"170.526012ms","start":"2026-05-08T18:34:54.236972Z","end":"2026-05-08T18:34:54.407498Z","steps":["trace[1054061702] 'process raft request' (duration: 170.276566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:34:54.407702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.879665ms","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:5"} {"level":"info","ts":"2026-05-08T18:34:54.407791Z","caller":"traceutil/trace.go:171","msg":"trace[2041264565] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8552; }","duration":"212.020199ms","start":"2026-05-08T18:34:54.195748Z","end":"2026-05-08T18:34:54.407768Z","steps":["trace[2041264565] 'agreement among raft nodes before linearized reading' (duration: 211.851305ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:35:40.810681Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000246b.wal"} {"level":"info","ts":"2026-05-08T18:36:59.657004Z","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-05-08T18:36:59.66227Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-08T18:36:59.662339Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-08T18:39:25.981036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8304} {"level":"info","ts":"2026-05-08T18:39:26.057698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8304,"took":"74.516617ms","hash":657185,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":26513408,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-08T18:39:26.057771Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":657185,"revision":8304,"compact-revision":6135} {"level":"info","ts":"2026-05-08T18:39:44.171963Z","caller":"traceutil/trace.go:171","msg":"trace[109012925] transaction","detail":"{read_only:false; response_revision:11083; number_of_response:1; }","duration":"223.677522ms","start":"2026-05-08T18:39:43.948252Z","end":"2026-05-08T18:39:44.171929Z","steps":["trace[109012925] 'process raft request' (duration: 207.969496ms)","trace[109012925] 'compare' (duration: 15.544082ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:39:44.217361Z","caller":"traceutil/trace.go:171","msg":"trace[145131924] transaction","detail":"{read_only:false; response_revision:11084; number_of_response:1; }","duration":"250.943785ms","start":"2026-05-08T18:39:43.966383Z","end":"2026-05-08T18:39:44.217327Z","steps":["trace[145131924] 'process raft request' (duration: 250.687299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:39:44.405832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.545711ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-08T18:39:44.405935Z","caller":"traceutil/trace.go:171","msg":"trace[2023399897] linearizableReadLoop","detail":"{readStateIndex:11457; appliedIndex:11456; }","duration":"162.255329ms","start":"2026-05-08T18:39:44.243661Z","end":"2026-05-08T18:39:44.405916Z","steps":["trace[2023399897] 'read index received' (duration: 68.541µs)","trace[2023399897] 'applied index is now lower than readState.Index' (duration: 162.185108ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:39:44.406125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.450964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:39:44.406195Z","caller":"traceutil/trace.go:171","msg":"trace[1854776472] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11084; }","duration":"162.531316ms","start":"2026-05-08T18:39:44.243651Z","end":"2026-05-08T18:39:44.406183Z","steps":["trace[1854776472] 'agreement among raft nodes before linearized reading' (duration: 162.371152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:39:44.406284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.457426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:39:44.40637Z","caller":"traceutil/trace.go:171","msg":"trace[1837628290] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11084; }","duration":"160.576079ms","start":"2026-05-08T18:39:44.245775Z","end":"2026-05-08T18:39:44.406351Z","steps":["trace[1837628290] 'agreement among raft nodes before linearized reading' (duration: 160.433965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:39:44.406497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.009004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:39:44.406587Z","caller":"traceutil/trace.go:171","msg":"trace[73983661] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11084; }","duration":"162.205379ms","start":"2026-05-08T18:39:44.244362Z","end":"2026-05-08T18:39:44.406567Z","steps":["trace[73983661] 'agreement among raft nodes before linearized reading' (duration: 161.865791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:39:44.406515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.788204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-05-08T18:39:44.406668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.770172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:39:44.406719Z","caller":"traceutil/trace.go:171","msg":"trace[631712774] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11084; }","duration":"160.993138ms","start":"2026-05-08T18:39:44.245683Z","end":"2026-05-08T18:39:44.406676Z","steps":["trace[631712774] 'agreement among raft nodes before linearized reading' (duration: 160.65673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:39:44.406729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.326097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-08T18:39:44.406766Z","caller":"traceutil/trace.go:171","msg":"trace[516523572] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11084; }","duration":"161.364948ms","start":"2026-05-08T18:39:44.245391Z","end":"2026-05-08T18:39:44.406756Z","steps":["trace[516523572] 'agreement among raft nodes before linearized reading' (duration: 161.227235ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:39:44.406779Z","caller":"traceutil/trace.go:171","msg":"trace[929825570] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11084; }","duration":"162.932065ms","start":"2026-05-08T18:39:44.24382Z","end":"2026-05-08T18:39:44.406752Z","steps":["trace[929825570] 'agreement among raft nodes before linearized reading' (duration: 162.774802ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:39:44.586798Z","caller":"traceutil/trace.go:171","msg":"trace[751057030] transaction","detail":"{read_only:false; response_revision:11086; number_of_response:1; }","duration":"111.888743ms","start":"2026-05-08T18:39:44.474867Z","end":"2026-05-08T18:39:44.586756Z","steps":["trace[751057030] 'process raft request' (duration: 62.874918ms)","trace[751057030] 'compare' (duration: 48.876211ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:39:44.893704Z","caller":"traceutil/trace.go:171","msg":"trace[589413379] transaction","detail":"{read_only:false; response_revision:11087; number_of_response:1; }","duration":"114.294651ms","start":"2026-05-08T18:39:44.779373Z","end":"2026-05-08T18:39:44.893668Z","steps":["trace[589413379] 'process raft request' (duration: 114.121866ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:44:25.987005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10976} {"level":"info","ts":"2026-05-08T18:44:26.069816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10976,"took":"80.263758ms","hash":3292475936,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":27836416,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-08T18:44:26.069873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3292475936,"revision":10976,"compact-revision":8304} {"level":"info","ts":"2026-05-08T18:46:00.807879Z","caller":"traceutil/trace.go:171","msg":"trace[386337256] transaction","detail":"{read_only:false; response_revision:13633; number_of_response:1; }","duration":"115.418779ms","start":"2026-05-08T18:46:00.69243Z","end":"2026-05-08T18:46:00.807849Z","steps":["trace[386337256] 'process raft request' (duration: 63.116009ms)","trace[386337256] 'compare' (duration: 52.179887ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:46:00.81737Z","caller":"traceutil/trace.go:171","msg":"trace[1623232216] linearizableReadLoop","detail":"{readStateIndex:14097; appliedIndex:14095; }","duration":"123.443323ms","start":"2026-05-08T18:46:00.693911Z","end":"2026-05-08T18:46:00.817354Z","steps":["trace[1623232216] 'read index received' (duration: 61.626804ms)","trace[1623232216] 'applied index is now lower than readState.Index' (duration: 61.815379ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:46:00.817585Z","caller":"traceutil/trace.go:171","msg":"trace[159679417] transaction","detail":"{read_only:false; response_revision:13634; number_of_response:1; }","duration":"124.610451ms","start":"2026-05-08T18:46:00.692963Z","end":"2026-05-08T18:46:00.817573Z","steps":["trace[159679417] 'process raft request' (duration: 124.278873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:46:00.817775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.849852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-08T18:46:00.817808Z","caller":"traceutil/trace.go:171","msg":"trace[1422377874] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13634; }","duration":"123.906644ms","start":"2026-05-08T18:46:00.693891Z","end":"2026-05-08T18:46:00.817797Z","steps":["trace[1422377874] 'agreement among raft nodes before linearized reading' (duration: 123.807402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:46:07.537115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.143705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:46:07.537226Z","caller":"traceutil/trace.go:171","msg":"trace[1304199486] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:13670; }","duration":"429.295419ms","start":"2026-05-08T18:46:07.10791Z","end":"2026-05-08T18:46:07.537205Z","steps":["trace[1304199486] 'count revisions from in-memory index tree' (duration: 429.073193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:46:07.537274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:46:07.107894Z","time spent":"429.36601ms","remote":"127.0.0.1:41068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-05-08T18:46:07.6372Z","caller":"traceutil/trace.go:171","msg":"trace[96752596] linearizableReadLoop","detail":"{readStateIndex:14135; appliedIndex:14134; }","duration":"120.489122ms","start":"2026-05-08T18:46:07.516684Z","end":"2026-05-08T18:46:07.637173Z","steps":["trace[96752596] 'read index received' (duration: 120.321278ms)","trace[96752596] 'applied index is now lower than readState.Index' (duration: 163.454µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:46:07.63726Z","caller":"traceutil/trace.go:171","msg":"trace[1646798433] transaction","detail":"{read_only:false; response_revision:13671; number_of_response:1; }","duration":"179.798401ms","start":"2026-05-08T18:46:07.457416Z","end":"2026-05-08T18:46:07.637215Z","steps":["trace[1646798433] 'process raft request' (duration: 179.604246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:46:07.638096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.394883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929931"} {"level":"info","ts":"2026-05-08T18:46:07.638145Z","caller":"traceutil/trace.go:171","msg":"trace[675435377] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13671; }","duration":"121.496705ms","start":"2026-05-08T18:46:07.516637Z","end":"2026-05-08T18:46:07.638133Z","steps":["trace[675435377] 'agreement among raft nodes before linearized reading' (duration: 120.660196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:46:07.728684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.582832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:46:07.728755Z","caller":"traceutil/trace.go:171","msg":"trace[1336272232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13672; }","duration":"127.654724ms","start":"2026-05-08T18:46:07.601082Z","end":"2026-05-08T18:46:07.728737Z","steps":["trace[1336272232] 'agreement among raft nodes before linearized reading' (duration: 127.557272ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:46:07.728747Z","caller":"traceutil/trace.go:171","msg":"trace[1474325836] transaction","detail":"{read_only:false; response_revision:13672; number_of_response:1; }","duration":"193.616272ms","start":"2026-05-08T18:46:07.53509Z","end":"2026-05-08T18:46:07.728706Z","steps":["trace[1474325836] 'process raft request' (duration: 193.401057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:46:07.72869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.576186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:46:07.728828Z","caller":"traceutil/trace.go:171","msg":"trace[1182090689] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13672; }","duration":"150.77261ms","start":"2026-05-08T18:46:07.578044Z","end":"2026-05-08T18:46:07.728817Z","steps":["trace[1182090689] 'agreement among raft nodes before linearized reading' (duration: 150.553975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:46:07.729591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.15217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-05-08T18:46:07.729666Z","caller":"traceutil/trace.go:171","msg":"trace[1640991528] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:13672; }","duration":"136.285643ms","start":"2026-05-08T18:46:07.593362Z","end":"2026-05-08T18:46:07.729648Z","steps":["trace[1640991528] 'agreement among raft nodes before linearized reading' (duration: 135.36348ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:47:49.116938Z","caller":"traceutil/trace.go:171","msg":"trace[1991770820] transaction","detail":"{read_only:false; response_revision:14873; number_of_response:1; }","duration":"139.388937ms","start":"2026-05-08T18:47:48.977489Z","end":"2026-05-08T18:47:49.116878Z","steps":["trace[1991770820] 'process raft request' (duration: 116.471285ms)","trace[1991770820] 'compare' (duration: 22.681576ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:49:25.995473Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13018} {"level":"info","ts":"2026-05-08T18:49:26.064914Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13018,"took":"67.127856ms","hash":396930794,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31076352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-08T18:49:26.065001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":396930794,"revision":13018,"compact-revision":10976} {"level":"info","ts":"2026-05-08T18:53:32.749167Z","caller":"traceutil/trace.go:171","msg":"trace[1171370348] linearizableReadLoop","detail":"{readStateIndex:18079; appliedIndex:18078; }","duration":"145.274149ms","start":"2026-05-08T18:53:32.603866Z","end":"2026-05-08T18:53:32.74914Z","steps":["trace[1171370348] 'read index received' (duration: 145.099285ms)","trace[1171370348] 'applied index is now lower than readState.Index' (duration: 173.564µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:53:32.749301Z","caller":"traceutil/trace.go:171","msg":"trace[356047870] transaction","detail":"{read_only:false; response_revision:17494; number_of_response:1; }","duration":"239.417837ms","start":"2026-05-08T18:53:32.509785Z","end":"2026-05-08T18:53:32.749203Z","steps":["trace[356047870] 'process raft request' (duration: 239.200492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:53:32.749618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.72105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T18:53:32.749858Z","caller":"traceutil/trace.go:171","msg":"trace[1753494378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17494; }","duration":"145.889874ms","start":"2026-05-08T18:53:32.603827Z","end":"2026-05-08T18:53:32.749717Z","steps":["trace[1753494378] 'agreement among raft nodes before linearized reading' (duration: 145.424933ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:54:26.005724Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15514} {"level":"info","ts":"2026-05-08T18:54:26.100802Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15514,"took":"91.757951ms","hash":2971814671,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":34062336,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-08T18:54:26.100871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2971814671,"revision":15514,"compact-revision":13018} {"level":"info","ts":"2026-05-08T18:57:05.865902Z","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-05-08T18:57:05.870867Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-08T18:57:05.871054Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-08T18:58:15.569941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.788465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T18:58:15.570079Z","caller":"traceutil/trace.go:171","msg":"trace[199730667] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:19742; }","duration":"135.98835ms","start":"2026-05-08T18:58:15.434069Z","end":"2026-05-08T18:58:15.570057Z","steps":["trace[199730667] 'count revisions from in-memory index tree' (duration: 135.704531ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:58:15.570437Z","caller":"traceutil/trace.go:171","msg":"trace[1511801700] transaction","detail":"{read_only:false; response_revision:19743; number_of_response:1; }","duration":"120.759551ms","start":"2026-05-08T18:58:15.449627Z","end":"2026-05-08T18:58:15.570386Z","steps":["trace[1511801700] 'process raft request' (duration: 70.667354ms)","trace[1511801700] 'compare' (duration: 49.76563ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:58:16.463833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.996286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T18:58:16.463945Z","caller":"traceutil/trace.go:171","msg":"trace[1152887536] linearizableReadLoop","detail":"{readStateIndex:20404; appliedIndex:20403; }","duration":"221.292466ms","start":"2026-05-08T18:58:16.242636Z","end":"2026-05-08T18:58:16.463928Z","steps":["trace[1152887536] 'read index received' (duration: 81.164637ms)","trace[1152887536] 'applied index is now lower than readState.Index' (duration: 140.126649ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:58:16.464071Z","caller":"traceutil/trace.go:171","msg":"trace[1656849250] transaction","detail":"{read_only:false; response_revision:19745; number_of_response:1; }","duration":"223.607072ms","start":"2026-05-08T18:58:16.240443Z","end":"2026-05-08T18:58:16.46405Z","steps":["trace[1656849250] 'process raft request' (duration: 83.286418ms)","trace[1656849250] 'compare' (duration: 139.884473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:58:16.464381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.729606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T18:58:16.464497Z","caller":"traceutil/trace.go:171","msg":"trace[570866529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19745; }","duration":"221.903391ms","start":"2026-05-08T18:58:16.242563Z","end":"2026-05-08T18:58:16.464467Z","steps":["trace[570866529] 'agreement among raft nodes before linearized reading' (duration: 221.595313ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:58:16.470556Z","caller":"traceutil/trace.go:171","msg":"trace[2144261886] transaction","detail":"{read_only:false; response_revision:19746; number_of_response:1; }","duration":"162.490238ms","start":"2026-05-08T18:58:16.308043Z","end":"2026-05-08T18:58:16.470533Z","steps":["trace[2144261886] 'process raft request' (duration: 162.368555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:58:16.480062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.467033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-08T18:58:16.480123Z","caller":"traceutil/trace.go:171","msg":"trace[275295640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19748; }","duration":"100.586125ms","start":"2026-05-08T18:58:16.379521Z","end":"2026-05-08T18:58:16.480108Z","steps":["trace[275295640] 'agreement among raft nodes before linearized reading' (duration: 100.422471ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:58:16.774928Z","caller":"traceutil/trace.go:171","msg":"trace[1812451397] transaction","detail":"{read_only:false; response_revision:19750; number_of_response:1; }","duration":"290.928305ms","start":"2026-05-08T18:58:16.483969Z","end":"2026-05-08T18:58:16.774898Z","steps":["trace[1812451397] 'process raft request' (duration: 290.768631ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:58:16.775324Z","caller":"traceutil/trace.go:171","msg":"trace[1189944097] transaction","detail":"{read_only:false; response_revision:19751; number_of_response:1; }","duration":"291.038408ms","start":"2026-05-08T18:58:16.484261Z","end":"2026-05-08T18:58:16.7753Z","steps":["trace[1189944097] 'process raft request' (duration: 290.597307ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:58:16.775539Z","caller":"traceutil/trace.go:171","msg":"trace[1878643302] linearizableReadLoop","detail":"{readStateIndex:20411; appliedIndex:20408; }","duration":"183.683909ms","start":"2026-05-08T18:58:16.591832Z","end":"2026-05-08T18:58:16.775516Z","steps":["trace[1878643302] 'read index received' (duration: 182.847039ms)","trace[1878643302] 'applied index is now lower than readState.Index' (duration: 833.21µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:58:16.77557Z","caller":"traceutil/trace.go:171","msg":"trace[1009582854] transaction","detail":"{read_only:false; response_revision:19752; number_of_response:1; }","duration":"289.08237ms","start":"2026-05-08T18:58:16.486461Z","end":"2026-05-08T18:58:16.775544Z","steps":["trace[1009582854] 'process raft request' (duration: 288.668911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:58:16.776203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.362976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T18:58:16.776257Z","caller":"traceutil/trace.go:171","msg":"trace[2139045479] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19752; }","duration":"184.467288ms","start":"2026-05-08T18:58:16.591773Z","end":"2026-05-08T18:58:16.776241Z","steps":["trace[2139045479] 'agreement among raft nodes before linearized reading' (duration: 183.832492ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:59:26.012003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18124} {"level":"info","ts":"2026-05-08T18:59:26.12468Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18124,"took":"109.551694ms","hash":203936383,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30760960,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-08T18:59:26.124757Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":203936383,"revision":18124,"compact-revision":15514} {"level":"info","ts":"2026-05-08T19:01:50.555181Z","caller":"traceutil/trace.go:171","msg":"trace[994100294] linearizableReadLoop","detail":"{readStateIndex:22871; appliedIndex:22870; }","duration":"185.00401ms","start":"2026-05-08T19:01:50.370154Z","end":"2026-05-08T19:01:50.555158Z","steps":["trace[994100294] 'read index received' (duration: 184.753834ms)","trace[994100294] 'applied index is now lower than readState.Index' (duration: 249.296µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:01:50.555235Z","caller":"traceutil/trace.go:171","msg":"trace[1533984052] transaction","detail":"{read_only:false; response_revision:22145; number_of_response:1; }","duration":"281.366498ms","start":"2026-05-08T19:01:50.273841Z","end":"2026-05-08T19:01:50.555208Z","steps":["trace[1533984052] 'process raft request' (duration: 281.141303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:01:50.555392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.230125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:01:50.555478Z","caller":"traceutil/trace.go:171","msg":"trace[595161132] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:22145; }","duration":"185.317827ms","start":"2026-05-08T19:01:50.370124Z","end":"2026-05-08T19:01:50.555442Z","steps":["trace[595161132] 'agreement among raft nodes before linearized reading' (duration: 185.170933ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:26.018437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20399} {"level":"info","ts":"2026-05-08T19:04:26.138225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20399,"took":"115.333693ms","hash":1294908088,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":43741184,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-08T19:04:26.138323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1294908088,"revision":20399,"compact-revision":18124} {"level":"warn","ts":"2026-05-08T19:04:36.846239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.642465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:04:36.846338Z","caller":"traceutil/trace.go:171","msg":"trace[402570165] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23888; }","duration":"106.803439ms","start":"2026-05-08T19:04:36.739511Z","end":"2026-05-08T19:04:36.846315Z","steps":["trace[402570165] 'range keys from in-memory index tree' (duration: 106.418999ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:41.717437Z","caller":"traceutil/trace.go:171","msg":"trace[1771426190] transaction","detail":"{read_only:false; response_revision:23929; number_of_response:1; }","duration":"100.959029ms","start":"2026-05-08T19:04:41.616443Z","end":"2026-05-08T19:04:41.717402Z","steps":["trace[1771426190] 'process raft request' (duration: 100.753934ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:42.436611Z","caller":"traceutil/trace.go:171","msg":"trace[1820927332] transaction","detail":"{read_only:false; response_revision:23931; number_of_response:1; }","duration":"167.396831ms","start":"2026-05-08T19:04:42.269176Z","end":"2026-05-08T19:04:42.436573Z","steps":["trace[1820927332] 'process raft request' (duration: 167.147325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:42.698256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.515852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:04:42.698493Z","caller":"traceutil/trace.go:171","msg":"trace[277472755] transaction","detail":"{read_only:false; response_revision:23932; number_of_response:1; }","duration":"256.829602ms","start":"2026-05-08T19:04:42.441635Z","end":"2026-05-08T19:04:42.698465Z","steps":["trace[277472755] 'process raft request' (duration: 117.013933ms)","trace[277472755] 'compare' (duration: 139.148584ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:42.698592Z","caller":"traceutil/trace.go:171","msg":"trace[294985958] linearizableReadLoop","detail":"{readStateIndex:24707; appliedIndex:24704; }","duration":"189.94048ms","start":"2026-05-08T19:04:42.508634Z","end":"2026-05-08T19:04:42.698574Z","steps":["trace[294985958] 'read index received' (duration: 49.988797ms)","trace[294985958] 'applied index is now lower than readState.Index' (duration: 139.950643ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:42.698889Z","caller":"traceutil/trace.go:171","msg":"trace[167710101] transaction","detail":"{read_only:false; response_revision:23933; number_of_response:1; }","duration":"256.888653ms","start":"2026-05-08T19:04:42.441983Z","end":"2026-05-08T19:04:42.698872Z","steps":["trace[167710101] 'process raft request' (duration: 256.415452ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:42.699014Z","caller":"traceutil/trace.go:171","msg":"trace[47150642] transaction","detail":"{read_only:false; response_revision:23934; number_of_response:1; }","duration":"205.933442ms","start":"2026-05-08T19:04:42.493041Z","end":"2026-05-08T19:04:42.698974Z","steps":["trace[47150642] 'process raft request' (duration: 205.453631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:42.6992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.187996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-08T19:04:42.699236Z","caller":"traceutil/trace.go:171","msg":"trace[727864299] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23934; }","duration":"190.606517ms","start":"2026-05-08T19:04:42.508619Z","end":"2026-05-08T19:04:42.699226Z","steps":["trace[727864299] 'agreement among raft nodes before linearized reading' (duration: 190.126205ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:42.950978Z","caller":"traceutil/trace.go:171","msg":"trace[224305479] transaction","detail":"{read_only:false; response_revision:23935; number_of_response:1; }","duration":"248.019352ms","start":"2026-05-08T19:04:42.702914Z","end":"2026-05-08T19:04:42.950934Z","steps":["trace[224305479] 'process raft request' (duration: 160.69975ms)","trace[224305479] 'compare' (duration: 87.131687ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:42.956637Z","caller":"traceutil/trace.go:171","msg":"trace[299990193] transaction","detail":"{read_only:false; response_revision:23936; number_of_response:1; }","duration":"252.504789ms","start":"2026-05-08T19:04:42.704122Z","end":"2026-05-08T19:04:42.956626Z","steps":["trace[299990193] 'process raft request' (duration: 252.335735ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:42.956688Z","caller":"traceutil/trace.go:171","msg":"trace[497448957] transaction","detail":"{read_only:false; response_revision:23937; number_of_response:1; }","duration":"252.061988ms","start":"2026-05-08T19:04:42.704594Z","end":"2026-05-08T19:04:42.956656Z","steps":["trace[497448957] 'process raft request' (duration: 251.934975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:42.956974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.529906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-08T19:04:42.957021Z","caller":"traceutil/trace.go:171","msg":"trace[1329548015] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23937; }","duration":"251.604268ms","start":"2026-05-08T19:04:42.705404Z","end":"2026-05-08T19:04:42.957009Z","steps":["trace[1329548015] 'agreement among raft nodes before linearized reading' (duration: 251.502704ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:42.9566Z","caller":"traceutil/trace.go:171","msg":"trace[1636438798] linearizableReadLoop","detail":"{readStateIndex:24710; appliedIndex:24707; }","duration":"251.164206ms","start":"2026-05-08T19:04:42.705423Z","end":"2026-05-08T19:04:42.956587Z","steps":["trace[1636438798] 'read index received' (duration: 158.200209ms)","trace[1636438798] 'applied index is now lower than readState.Index' (duration: 92.962997ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:47.097185Z","caller":"traceutil/trace.go:171","msg":"trace[586755560] linearizableReadLoop","detail":"{readStateIndex:24741; appliedIndex:24739; }","duration":"125.302471ms","start":"2026-05-08T19:04:46.971861Z","end":"2026-05-08T19:04:47.097164Z","steps":["trace[586755560] 'read index received' (duration: 95.60471ms)","trace[586755560] 'applied index is now lower than readState.Index' (duration: 29.696851ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:47.09727Z","caller":"traceutil/trace.go:171","msg":"trace[1701862245] transaction","detail":"{read_only:false; response_revision:23967; number_of_response:1; }","duration":"125.968718ms","start":"2026-05-08T19:04:46.971266Z","end":"2026-05-08T19:04:47.097235Z","steps":["trace[1701862245] 'process raft request' (duration: 125.787173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:47.097424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.543497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-08T19:04:47.09748Z","caller":"traceutil/trace.go:171","msg":"trace[1747192453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23967; }","duration":"125.63394ms","start":"2026-05-08T19:04:46.971833Z","end":"2026-05-08T19:04:47.097467Z","steps":["trace[1747192453] 'agreement among raft nodes before linearized reading' (duration: 125.406714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:47.097546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.649247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-08T19:04:47.0976Z","caller":"traceutil/trace.go:171","msg":"trace[694577560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23967; }","duration":"124.757439ms","start":"2026-05-08T19:04:46.972834Z","end":"2026-05-08T19:04:47.097591Z","steps":["trace[694577560] 'agreement among raft nodes before linearized reading' (duration: 124.557134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:47.097841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.564633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:04:47.097915Z","caller":"traceutil/trace.go:171","msg":"trace[709476992] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23967; }","duration":"121.650055ms","start":"2026-05-08T19:04:46.976242Z","end":"2026-05-08T19:04:47.097892Z","steps":["trace[709476992] 'agreement among raft nodes before linearized reading' (duration: 121.248796ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:47.225326Z","caller":"traceutil/trace.go:171","msg":"trace[1821083821] transaction","detail":"{read_only:false; response_revision:23968; number_of_response:1; }","duration":"123.865177ms","start":"2026-05-08T19:04:47.10144Z","end":"2026-05-08T19:04:47.225305Z","steps":["trace[1821083821] 'process raft request' (duration: 84.818662ms)","trace[1821083821] 'compare' (duration: 38.924093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:04:47.430735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.246106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:04:47.43099Z","caller":"traceutil/trace.go:171","msg":"trace[1768511226] transaction","detail":"{read_only:false; response_revision:23969; number_of_response:1; }","duration":"328.867099ms","start":"2026-05-08T19:04:47.102108Z","end":"2026-05-08T19:04:47.430975Z","steps":["trace[1768511226] 'process raft request' (duration: 227.317376ms)","trace[1768511226] 'compare' (duration: 101.157963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:04:47.431067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:04:47.102088Z","time spent":"328.933921ms","remote":"127.0.0.1:39096","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-05-08T19:04:47.431417Z","caller":"traceutil/trace.go:171","msg":"trace[454785684] transaction","detail":"{read_only:false; response_revision:23970; number_of_response:1; }","duration":"328.372087ms","start":"2026-05-08T19:04:47.103016Z","end":"2026-05-08T19:04:47.431389Z","steps":["trace[454785684] 'process raft request' (duration: 327.792933ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:47.431511Z","caller":"traceutil/trace.go:171","msg":"trace[179771951] transaction","detail":"{read_only:false; response_revision:23971; number_of_response:1; }","duration":"188.329661ms","start":"2026-05-08T19:04:47.243164Z","end":"2026-05-08T19:04:47.431494Z","steps":["trace[179771951] 'process raft request' (duration: 188.149997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:47.431564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:04:47.102991Z","time spent":"328.494819ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:04:47.989613Z","caller":"traceutil/trace.go:171","msg":"trace[602043222] transaction","detail":"{read_only:false; response_revision:23974; number_of_response:1; }","duration":"107.145717ms","start":"2026-05-08T19:04:47.882442Z","end":"2026-05-08T19:04:47.989587Z","steps":["trace[602043222] 'process raft request' (duration: 106.996123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:48.204697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.036773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-08T19:04:48.204773Z","caller":"traceutil/trace.go:171","msg":"trace[1504342789] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23974; }","duration":"147.154736ms","start":"2026-05-08T19:04:48.0576Z","end":"2026-05-08T19:04:48.204755Z","steps":["trace[1504342789] 'range keys from in-memory index tree' (duration: 146.649804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:48.796288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.01129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:04:48.79639Z","caller":"traceutil/trace.go:171","msg":"trace[324102064] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23975; }","duration":"154.164764ms","start":"2026-05-08T19:04:48.6422Z","end":"2026-05-08T19:04:48.796365Z","steps":["trace[324102064] 'count revisions from in-memory index tree' (duration: 153.889046ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:48.980672Z","caller":"traceutil/trace.go:171","msg":"trace[1993792649] linearizableReadLoop","detail":"{readStateIndex:24752; appliedIndex:24751; }","duration":"114.184016ms","start":"2026-05-08T19:04:48.866464Z","end":"2026-05-08T19:04:48.980648Z","steps":["trace[1993792649] 'read index received' (duration: 110.248861ms)","trace[1993792649] 'applied index is now lower than readState.Index' (duration: 3.933055ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:48.981Z","caller":"traceutil/trace.go:171","msg":"trace[177926574] transaction","detail":"{read_only:false; response_revision:23978; number_of_response:1; }","duration":"179.92008ms","start":"2026-05-08T19:04:48.801045Z","end":"2026-05-08T19:04:48.980965Z","steps":["trace[177926574] 'process raft request' (duration: 175.650658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:48.98128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.80143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:04:48.981334Z","caller":"traceutil/trace.go:171","msg":"trace[2145751013] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:23978; }","duration":"114.894693ms","start":"2026-05-08T19:04:48.866424Z","end":"2026-05-08T19:04:48.981319Z","steps":["trace[2145751013] 'agreement among raft nodes before linearized reading' (duration: 114.804231ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:49.243805Z","caller":"traceutil/trace.go:171","msg":"trace[1780720381] linearizableReadLoop","detail":"{readStateIndex:24753; appliedIndex:24752; }","duration":"172.739059ms","start":"2026-05-08T19:04:49.071042Z","end":"2026-05-08T19:04:49.243781Z","steps":["trace[1780720381] 'read index received' (duration: 82.155658ms)","trace[1780720381] 'applied index is now lower than readState.Index' (duration: 90.581991ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:04:49.244078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.985844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-08T19:04:49.244118Z","caller":"traceutil/trace.go:171","msg":"trace[835388782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23978; }","duration":"173.111567ms","start":"2026-05-08T19:04:49.070996Z","end":"2026-05-08T19:04:49.244107Z","steps":["trace[835388782] 'agreement among raft nodes before linearized reading' (duration: 172.895292ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:49.285716Z","caller":"traceutil/trace.go:171","msg":"trace[1831733042] transaction","detail":"{read_only:false; response_revision:23979; number_of_response:1; }","duration":"183.043906ms","start":"2026-05-08T19:04:49.102653Z","end":"2026-05-08T19:04:49.285697Z","steps":["trace[1831733042] 'process raft request' (duration: 182.927283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:49.59499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.381601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:04:49.595293Z","caller":"traceutil/trace.go:171","msg":"trace[114354610] transaction","detail":"{read_only:false; response_revision:23980; number_of_response:1; }","duration":"345.932386ms","start":"2026-05-08T19:04:49.249327Z","end":"2026-05-08T19:04:49.59526Z","steps":["trace[114354610] 'process raft request' (duration: 190.188056ms)","trace[114354610] 'compare' (duration: 155.061344ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:49.595323Z","caller":"traceutil/trace.go:171","msg":"trace[1243694754] transaction","detail":"{read_only:false; response_revision:23981; number_of_response:1; }","duration":"345.967137ms","start":"2026-05-08T19:04:49.249335Z","end":"2026-05-08T19:04:49.595302Z","steps":["trace[1243694754] 'process raft request' (duration: 345.782223ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:49.595363Z","caller":"traceutil/trace.go:171","msg":"trace[481117215] transaction","detail":"{read_only:false; response_revision:23982; number_of_response:1; }","duration":"303.158952ms","start":"2026-05-08T19:04:49.292183Z","end":"2026-05-08T19:04:49.595342Z","steps":["trace[481117215] 'process raft request' (duration: 303.05588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:49.595394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:04:49.249315Z","time spent":"346.04672ms","remote":"127.0.0.1:39096","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-05-08T19:04:49.595426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:04:49.249303Z","time spent":"346.05986ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:04:49.595468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:04:49.292143Z","time spent":"303.275895ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:04:49.595536Z","caller":"traceutil/trace.go:171","msg":"trace[1208882506] transaction","detail":"{read_only:false; response_revision:23983; number_of_response:1; }","duration":"159.109051ms","start":"2026-05-08T19:04:49.43642Z","end":"2026-05-08T19:04:49.595529Z","steps":["trace[1208882506] 'process raft request' (duration: 158.874296ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:49.595682Z","caller":"traceutil/trace.go:171","msg":"trace[502791538] linearizableReadLoop","detail":"{readStateIndex:24757; appliedIndex:24754; }","duration":"189.248433ms","start":"2026-05-08T19:04:49.406427Z","end":"2026-05-08T19:04:49.595676Z","steps":["trace[502791538] 'read index received' (duration: 33.039792ms)","trace[502791538] 'applied index is now lower than readState.Index' (duration: 156.207961ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:49.595761Z","caller":"traceutil/trace.go:171","msg":"trace[292435054] transaction","detail":"{read_only:false; response_revision:23984; number_of_response:1; }","duration":"158.069276ms","start":"2026-05-08T19:04:49.437686Z","end":"2026-05-08T19:04:49.595756Z","steps":["trace[292435054] 'process raft request' (duration: 157.80861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:49.595972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.5422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:04:49.596Z","caller":"traceutil/trace.go:171","msg":"trace[1365719776] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:23984; }","duration":"189.611451ms","start":"2026-05-08T19:04:49.406382Z","end":"2026-05-08T19:04:49.595993Z","steps":["trace[1365719776] 'agreement among raft nodes before linearized reading' (duration: 189.56658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:49.59599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.387165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-08T19:04:49.596042Z","caller":"traceutil/trace.go:171","msg":"trace[1888832240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23984; }","duration":"161.486557ms","start":"2026-05-08T19:04:49.434541Z","end":"2026-05-08T19:04:49.596028Z","steps":["trace[1888832240] 'agreement among raft nodes before linearized reading' (duration: 161.322453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:49.596603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.759323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:04:49.5967Z","caller":"traceutil/trace.go:171","msg":"trace[695535494] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23984; }","duration":"158.855206ms","start":"2026-05-08T19:04:49.437825Z","end":"2026-05-08T19:04:49.59668Z","steps":["trace[695535494] 'agreement among raft nodes before linearized reading' (duration: 158.404045ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:49.867804Z","caller":"traceutil/trace.go:171","msg":"trace[23481402] transaction","detail":"{read_only:false; response_revision:23986; number_of_response:1; }","duration":"266.379342ms","start":"2026-05-08T19:04:49.601388Z","end":"2026-05-08T19:04:49.867768Z","steps":["trace[23481402] 'process raft request' (duration: 185.947315ms)","trace[23481402] 'compare' (duration: 80.219832ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:04:49.867908Z","caller":"traceutil/trace.go:171","msg":"trace[2143135315] linearizableReadLoop","detail":"{readStateIndex:24761; appliedIndex:24760; }","duration":"266.431353ms","start":"2026-05-08T19:04:49.601454Z","end":"2026-05-08T19:04:49.867885Z","steps":["trace[2143135315] 'read index received' (duration: 13.193357ms)","trace[2143135315] 'applied index is now lower than readState.Index' (duration: 253.231506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:04:49.868116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.152029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:04:49.868172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.204417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:04:49.868204Z","caller":"traceutil/trace.go:171","msg":"trace[1451083130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23987; }","duration":"264.270573ms","start":"2026-05-08T19:04:49.603916Z","end":"2026-05-08T19:04:49.868187Z","steps":["trace[1451083130] 'agreement among raft nodes before linearized reading' (duration: 264.136879ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:04:49.868245Z","caller":"traceutil/trace.go:171","msg":"trace[1114030788] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:23987; }","duration":"175.395113ms","start":"2026-05-08T19:04:49.692834Z","end":"2026-05-08T19:04:49.868229Z","steps":["trace[1114030788] 'agreement among raft nodes before linearized reading' (duration: 175.253889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:04:49.868116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.65306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-08T19:04:49.868519Z","caller":"traceutil/trace.go:171","msg":"trace[1506784962] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:23987; }","duration":"267.028778ms","start":"2026-05-08T19:04:49.60145Z","end":"2026-05-08T19:04:49.868479Z","steps":["trace[1506784962] 'agreement among raft nodes before linearized reading' (duration: 266.524817ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:00.14173Z","caller":"traceutil/trace.go:171","msg":"trace[1582368887] transaction","detail":"{read_only:false; response_revision:24062; number_of_response:1; }","duration":"111.572282ms","start":"2026-05-08T19:05:00.030121Z","end":"2026-05-08T19:05:00.141693Z","steps":["trace[1582368887] 'process raft request' (duration: 111.366848ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:00.306223Z","caller":"traceutil/trace.go:171","msg":"trace[868686247] transaction","detail":"{read_only:false; response_revision:24063; number_of_response:1; }","duration":"161.780186ms","start":"2026-05-08T19:05:00.144409Z","end":"2026-05-08T19:05:00.306189Z","steps":["trace[868686247] 'process raft request' (duration: 161.575471ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:00.58579Z","caller":"traceutil/trace.go:171","msg":"trace[714078280] transaction","detail":"{read_only:false; response_revision:24064; number_of_response:1; }","duration":"441.253051ms","start":"2026-05-08T19:05:00.144517Z","end":"2026-05-08T19:05:00.58577Z","steps":["trace[714078280] 'process raft request' (duration: 441.140808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:00.585902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:00.144493Z","time spent":"441.357032ms","remote":"127.0.0.1:39084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5163,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T19:05:00.592669Z","caller":"traceutil/trace.go:171","msg":"trace[330852589] transaction","detail":"{read_only:false; response_revision:24065; number_of_response:1; }","duration":"282.15973ms","start":"2026-05-08T19:05:00.310485Z","end":"2026-05-08T19:05:00.592644Z","steps":["trace[330852589] 'process raft request' (duration: 281.904234ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:00.592924Z","caller":"traceutil/trace.go:171","msg":"trace[1051310513] transaction","detail":"{read_only:false; response_revision:24066; number_of_response:1; }","duration":"278.604684ms","start":"2026-05-08T19:05:00.314239Z","end":"2026-05-08T19:05:00.592844Z","steps":["trace[1051310513] 'process raft request' (duration: 278.269097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:00.593114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.630913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:00.59291Z","caller":"traceutil/trace.go:171","msg":"trace[508043410] linearizableReadLoop","detail":"{readStateIndex:24842; appliedIndex:24841; }","duration":"280.403127ms","start":"2026-05-08T19:05:00.312474Z","end":"2026-05-08T19:05:00.592877Z","steps":["trace[508043410] 'read index received' (duration: 273.689827ms)","trace[508043410] 'applied index is now lower than readState.Index' (duration: 6.7127ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:00.5932Z","caller":"traceutil/trace.go:171","msg":"trace[1821200106] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:24067; }","duration":"280.691165ms","start":"2026-05-08T19:05:00.312453Z","end":"2026-05-08T19:05:00.593144Z","steps":["trace[1821200106] 'agreement among raft nodes before linearized reading' (duration: 280.596822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:03.952072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.692114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:03.952217Z","caller":"traceutil/trace.go:171","msg":"trace[1976777678] transaction","detail":"{read_only:false; response_revision:24173; number_of_response:1; }","duration":"261.794951ms","start":"2026-05-08T19:05:03.690395Z","end":"2026-05-08T19:05:03.95219Z","steps":["trace[1976777678] 'process raft request' (duration: 66.867041ms)","trace[1976777678] 'compare' (duration: 194.564541ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:04.045859Z","caller":"traceutil/trace.go:171","msg":"trace[1105818235] linearizableReadLoop","detail":"{readStateIndex:24952; appliedIndex:24950; }","duration":"195.620547ms","start":"2026-05-08T19:05:03.850218Z","end":"2026-05-08T19:05:04.045839Z","steps":["trace[1105818235] 'read index received' (duration: 194.97558ms)","trace[1105818235] 'applied index is now lower than readState.Index' (duration: 644.177µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:04.045907Z","caller":"traceutil/trace.go:171","msg":"trace[1995212307] transaction","detail":"{read_only:false; response_revision:24174; number_of_response:1; }","duration":"354.868502ms","start":"2026-05-08T19:05:03.691019Z","end":"2026-05-08T19:05:04.045887Z","steps":["trace[1995212307] 'process raft request' (duration: 354.672426ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:04.045994Z","caller":"traceutil/trace.go:171","msg":"trace[230180363] transaction","detail":"{read_only:false; response_revision:24175; number_of_response:1; }","duration":"354.619596ms","start":"2026-05-08T19:05:03.691342Z","end":"2026-05-08T19:05:04.045961Z","steps":["trace[230180363] 'process raft request' (duration: 354.471272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.046021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:03.690995Z","time spent":"354.951053ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:04.046069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:03.691332Z","time spent":"354.694367ms","remote":"127.0.0.1:39096","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-05-08T19:05:04.046064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.871752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-08T19:05:04.0461Z","caller":"traceutil/trace.go:171","msg":"trace[4426858] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7; range_end:; response_count:1; response_revision:24175; }","duration":"195.994686ms","start":"2026-05-08T19:05:03.850094Z","end":"2026-05-08T19:05:04.046089Z","steps":["trace[4426858] 'agreement among raft nodes before linearized reading' (duration: 195.838962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.321848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.341645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-05-08T19:05:04.322079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.991663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:05:04.322096Z","caller":"traceutil/trace.go:171","msg":"trace[1223236565] transaction","detail":"{read_only:false; response_revision:24176; number_of_response:1; }","duration":"403.583099ms","start":"2026-05-08T19:05:03.918381Z","end":"2026-05-08T19:05:04.321964Z","steps":["trace[1223236565] 'process raft request' (duration: 270.050379ms)","trace[1223236565] 'compare' (duration: 132.948406ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:04.32214Z","caller":"traceutil/trace.go:171","msg":"trace[1274146598] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24176; }","duration":"385.090625ms","start":"2026-05-08T19:05:03.937035Z","end":"2026-05-08T19:05:04.322125Z","steps":["trace[1274146598] 'agreement among raft nodes before linearized reading' (duration: 384.917311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.322185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:03.937013Z","time spent":"385.163347ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T19:05:04.322257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:03.918353Z","time spent":"403.818804ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:04.322303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.984443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-05-08T19:05:04.322407Z","caller":"traceutil/trace.go:171","msg":"trace[2062671468] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24176; }","duration":"471.083586ms","start":"2026-05-08T19:05:03.851272Z","end":"2026-05-08T19:05:04.322356Z","steps":["trace[2062671468] 'agreement among raft nodes before linearized reading' (duration: 470.868131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.322438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:03.851262Z","time spent":"471.169258ms","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1658,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-05-08T19:05:04.321944Z","caller":"traceutil/trace.go:171","msg":"trace[104290529] linearizableReadLoop","detail":"{readStateIndex:24953; appliedIndex:24952; }","duration":"276.011293ms","start":"2026-05-08T19:05:04.045916Z","end":"2026-05-08T19:05:04.321927Z","steps":["trace[104290529] 'read index received' (duration: 142.616876ms)","trace[104290529] 'applied index is now lower than readState.Index' (duration: 133.393047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:04.661634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.527541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:04.661958Z","caller":"traceutil/trace.go:171","msg":"trace[1519946675] transaction","detail":"{read_only:false; response_revision:24177; number_of_response:1; }","duration":"608.504868ms","start":"2026-05-08T19:05:04.05341Z","end":"2026-05-08T19:05:04.661915Z","steps":["trace[1519946675] 'process raft request' (duration: 428.612708ms)","trace[1519946675] 'compare' (duration: 179.384858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:04.662039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:04.053382Z","time spent":"608.619711ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:04.662479Z","caller":"traceutil/trace.go:171","msg":"trace[188225771] transaction","detail":"{read_only:false; response_revision:24180; number_of_response:1; }","duration":"335.692222ms","start":"2026-05-08T19:05:04.326771Z","end":"2026-05-08T19:05:04.662463Z","steps":["trace[188225771] 'process raft request' (duration: 335.585219ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:04.662477Z","caller":"traceutil/trace.go:171","msg":"trace[666766628] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"368.441787ms","start":"2026-05-08T19:05:04.293997Z","end":"2026-05-08T19:05:04.662439Z","steps":["trace[666766628] 'process raft request' (duration: 368.223382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.66256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:04.326752Z","time spent":"335.766934ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:04.66262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:04.293975Z","time spent":"368.5674ms","remote":"127.0.0.1:39096","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-05-08T19:05:04.66253Z","caller":"traceutil/trace.go:171","msg":"trace[932560470] transaction","detail":"{read_only:false; response_revision:24178; number_of_response:1; }","duration":"506.159076ms","start":"2026-05-08T19:05:04.156069Z","end":"2026-05-08T19:05:04.662228Z","steps":["trace[932560470] 'process raft request' (duration: 505.662684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.662892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:04.156041Z","time spent":"506.738619ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:04.735072Z","caller":"traceutil/trace.go:171","msg":"trace[915111682] linearizableReadLoop","detail":"{readStateIndex:24956; appliedIndex:24953; }","duration":"412.410011ms","start":"2026-05-08T19:05:04.322625Z","end":"2026-05-08T19:05:04.735035Z","steps":["trace[915111682] 'read index received' (duration: 159.408899ms)","trace[915111682] 'applied index is now lower than readState.Index' (duration: 252.999352ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:04.735312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.146479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-08T19:05:04.735646Z","caller":"traceutil/trace.go:171","msg":"trace[2098939781] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7; range_end:; response_count:1; response_revision:24180; }","duration":"436.526658ms","start":"2026-05-08T19:05:04.299106Z","end":"2026-05-08T19:05:04.735633Z","steps":["trace[2098939781] 'agreement among raft nodes before linearized reading' (duration: 436.098968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.73569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:04.299089Z","time spent":"436.591249ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7\" "} {"level":"warn","ts":"2026-05-08T19:05:04.750261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.397001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4015115f-7fdd-4874-803d-885fef73ac40\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-05-08T19:05:04.750325Z","caller":"traceutil/trace.go:171","msg":"trace[510922320] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4015115f-7fdd-4874-803d-885fef73ac40; range_end:; response_count:1; response_revision:24181; }","duration":"425.498943ms","start":"2026-05-08T19:05:04.324809Z","end":"2026-05-08T19:05:04.750308Z","steps":["trace[510922320] 'agreement among raft nodes before linearized reading' (duration: 425.322429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.750647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:04.324789Z","time spent":"425.838342ms","remote":"127.0.0.1:38978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1318,"request content":"key:\"/registry/persistentvolumes/pvc-4015115f-7fdd-4874-803d-885fef73ac40\" "} {"level":"warn","ts":"2026-05-08T19:05:04.750655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.775152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:05:04.750492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.988221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:04.750814Z","caller":"traceutil/trace.go:171","msg":"trace[1040765376] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:24181; }","duration":"271.955306ms","start":"2026-05-08T19:05:04.478797Z","end":"2026-05-08T19:05:04.750753Z","steps":["trace[1040765376] 'agreement among raft nodes before linearized reading' (duration: 271.72121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.75086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.513995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18adac8202af6c1c\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-05-08T19:05:04.750874Z","caller":"traceutil/trace.go:171","msg":"trace[1835299028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24181; }","duration":"147.416781ms","start":"2026-05-08T19:05:04.603428Z","end":"2026-05-08T19:05:04.750844Z","steps":["trace[1835299028] 'agreement among raft nodes before linearized reading' (duration: 146.95824ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:04.750934Z","caller":"traceutil/trace.go:171","msg":"trace[657325088] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18adac8202af6c1c; range_end:; response_count:1; response_revision:24181; }","duration":"415.626637ms","start":"2026-05-08T19:05:04.335292Z","end":"2026-05-08T19:05:04.750919Z","steps":["trace[657325088] 'agreement among raft nodes before linearized reading' (duration: 415.401531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:04.750975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:04.33528Z","time spent":"415.686439ms","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1034,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18adac8202af6c1c\" "} {"level":"info","ts":"2026-05-08T19:05:04.75044Z","caller":"traceutil/trace.go:171","msg":"trace[1948051902] transaction","detail":"{read_only:false; response_revision:24181; number_of_response:1; }","duration":"141.11308ms","start":"2026-05-08T19:05:04.609298Z","end":"2026-05-08T19:05:04.750411Z","steps":["trace[1948051902] 'process raft request' (duration: 140.563427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:05.106086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.21733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:05.10622Z","caller":"traceutil/trace.go:171","msg":"trace[1912683713] linearizableReadLoop","detail":"{readStateIndex:24962; appliedIndex:24961; }","duration":"189.380578ms","start":"2026-05-08T19:05:04.916817Z","end":"2026-05-08T19:05:05.106198Z","steps":["trace[1912683713] 'read index received' (duration: 50.87882ms)","trace[1912683713] 'applied index is now lower than readState.Index' (duration: 138.499848ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:05.106571Z","caller":"traceutil/trace.go:171","msg":"trace[2111434357] transaction","detail":"{read_only:false; response_revision:24183; number_of_response:1; }","duration":"240.238065ms","start":"2026-05-08T19:05:04.866312Z","end":"2026-05-08T19:05:05.10655Z","steps":["trace[2111434357] 'process raft request' (duration: 101.459291ms)","trace[2111434357] 'compare' (duration: 137.964865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:05.106604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.768117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-08T19:05:05.106638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.638338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:05.106695Z","caller":"traceutil/trace.go:171","msg":"trace[1750939492] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24183; }","duration":"132.738879ms","start":"2026-05-08T19:05:04.973939Z","end":"2026-05-08T19:05:05.106678Z","steps":["trace[1750939492] 'agreement among raft nodes before linearized reading' (duration: 132.637447ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:05.10669Z","caller":"traceutil/trace.go:171","msg":"trace[1929242204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24183; }","duration":"189.89518ms","start":"2026-05-08T19:05:04.916772Z","end":"2026-05-08T19:05:05.106667Z","steps":["trace[1929242204] 'agreement among raft nodes before linearized reading' (duration: 189.575212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:05.383972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.936236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:05.384159Z","caller":"traceutil/trace.go:171","msg":"trace[24347354] transaction","detail":"{read_only:false; response_revision:24185; number_of_response:1; }","duration":"272.319633ms","start":"2026-05-08T19:05:05.111827Z","end":"2026-05-08T19:05:05.384147Z","steps":["trace[24347354] 'process raft request' (duration: 272.242741ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:05.384194Z","caller":"traceutil/trace.go:171","msg":"trace[1916905652] transaction","detail":"{read_only:false; response_revision:24184; number_of_response:1; }","duration":"273.296706ms","start":"2026-05-08T19:05:05.110856Z","end":"2026-05-08T19:05:05.384153Z","steps":["trace[1916905652] 'process raft request' (duration: 122.104514ms)","trace[1916905652] 'compare' (duration: 150.807363ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:05.684978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.420188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:05.685271Z","caller":"traceutil/trace.go:171","msg":"trace[1662228325] linearizableReadLoop","detail":"{readStateIndex:24965; appliedIndex:24964; }","duration":"138.598151ms","start":"2026-05-08T19:05:05.546628Z","end":"2026-05-08T19:05:05.685226Z","steps":["trace[1662228325] 'read index received' (duration: 40.461µs)","trace[1662228325] 'applied index is now lower than readState.Index' (duration: 138.55631ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:05.685336Z","caller":"traceutil/trace.go:171","msg":"trace[1588671952] transaction","detail":"{read_only:false; response_revision:24186; number_of_response:1; }","duration":"351.05456ms","start":"2026-05-08T19:05:05.334235Z","end":"2026-05-08T19:05:05.685289Z","steps":["trace[1588671952] 'process raft request' (duration: 180.595856ms)","trace[1588671952] 'compare' (duration: 169.04896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:05.685417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.752074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:05:05.685495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:05.334189Z","time spent":"351.223904ms","remote":"127.0.0.1:39096","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-05-08T19:05:05.685502Z","caller":"traceutil/trace.go:171","msg":"trace[422806578] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:24186; }","duration":"138.861467ms","start":"2026-05-08T19:05:05.54662Z","end":"2026-05-08T19:05:05.685482Z","steps":["trace[422806578] 'agreement among raft nodes before linearized reading' (duration: 138.695133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:05.997026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.386835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:05.99722Z","caller":"traceutil/trace.go:171","msg":"trace[689967378] linearizableReadLoop","detail":"{readStateIndex:24968; appliedIndex:24966; }","duration":"294.574647ms","start":"2026-05-08T19:05:05.702628Z","end":"2026-05-08T19:05:05.997203Z","steps":["trace[689967378] 'read index received' (duration: 127.754791ms)","trace[689967378] 'applied index is now lower than readState.Index' (duration: 166.818906ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:05.9973Z","caller":"traceutil/trace.go:171","msg":"trace[1535132531] transaction","detail":"{read_only:false; response_revision:24188; number_of_response:1; }","duration":"306.076733ms","start":"2026-05-08T19:05:05.69114Z","end":"2026-05-08T19:05:05.997217Z","steps":["trace[1535132531] 'process raft request' (duration: 139.350939ms)","trace[1535132531] 'compare' (duration: 166.230652ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:05.997415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.702904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:05.997457Z","caller":"traceutil/trace.go:171","msg":"trace[2105097555] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:24189; }","duration":"295.779875ms","start":"2026-05-08T19:05:05.701665Z","end":"2026-05-08T19:05:05.997445Z","steps":["trace[2105097555] 'agreement among raft nodes before linearized reading' (duration: 295.674932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:05.997446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:05.691121Z","time spent":"306.240026ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:05.997322Z","caller":"traceutil/trace.go:171","msg":"trace[324545585] transaction","detail":"{read_only:false; response_revision:24189; number_of_response:1; }","duration":"306.138904ms","start":"2026-05-08T19:05:05.691173Z","end":"2026-05-08T19:05:05.997312Z","steps":["trace[324545585] 'process raft request' (duration: 305.95642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:05.997683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.823979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:05.997757Z","caller":"traceutil/trace.go:171","msg":"trace[864926601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24189; }","duration":"283.927422ms","start":"2026-05-08T19:05:05.713812Z","end":"2026-05-08T19:05:05.997739Z","steps":["trace[864926601] 'agreement among raft nodes before linearized reading' (duration: 283.775078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:05.99779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.187835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-08T19:05:05.997859Z","caller":"traceutil/trace.go:171","msg":"trace[819190836] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7; range_end:; response_count:1; response_revision:24189; }","duration":"258.283217ms","start":"2026-05-08T19:05:05.739557Z","end":"2026-05-08T19:05:05.99784Z","steps":["trace[819190836] 'agreement among raft nodes before linearized reading' (duration: 258.066752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:05.997774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:05.691162Z","time spent":"306.514102ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:06.350734Z","caller":"traceutil/trace.go:171","msg":"trace[1231682163] transaction","detail":"{read_only:false; response_revision:24191; number_of_response:1; }","duration":"295.95519ms","start":"2026-05-08T19:05:06.054744Z","end":"2026-05-08T19:05:06.350699Z","steps":["trace[1231682163] 'process raft request' (duration: 295.784756ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:06.510137Z","caller":"traceutil/trace.go:171","msg":"trace[114112639] transaction","detail":"{read_only:false; response_revision:24193; number_of_response:1; }","duration":"181.859537ms","start":"2026-05-08T19:05:06.328259Z","end":"2026-05-08T19:05:06.510119Z","steps":["trace[114112639] 'process raft request' (duration: 181.815146ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:06.510188Z","caller":"traceutil/trace.go:171","msg":"trace[1293445250] transaction","detail":"{read_only:false; response_revision:24192; number_of_response:1; }","duration":"455.202735ms","start":"2026-05-08T19:05:06.054945Z","end":"2026-05-08T19:05:06.510148Z","steps":["trace[1293445250] 'process raft request' (duration: 398.907596ms)","trace[1293445250] 'compare' (duration: 56.114355ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:06.51036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:06.054936Z","time spent":"455.291657ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:06.909677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.739396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:05:06.909767Z","caller":"traceutil/trace.go:171","msg":"trace[50333259] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24193; }","duration":"242.872639ms","start":"2026-05-08T19:05:06.666872Z","end":"2026-05-08T19:05:06.909745Z","steps":["trace[50333259] 'range keys from in-memory index tree' (duration: 242.563322ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:06.910102Z","caller":"traceutil/trace.go:171","msg":"trace[499198284] transaction","detail":"{read_only:false; response_revision:24194; number_of_response:1; }","duration":"241.230489ms","start":"2026-05-08T19:05:06.668644Z","end":"2026-05-08T19:05:06.909875Z","steps":["trace[499198284] 'process raft request' (duration: 152.752229ms)","trace[499198284] 'compare' (duration: 88.04775ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:06.975028Z","caller":"traceutil/trace.go:171","msg":"trace[1751764503] transaction","detail":"{read_only:false; response_revision:24195; number_of_response:1; }","duration":"219.561549ms","start":"2026-05-08T19:05:06.755446Z","end":"2026-05-08T19:05:06.975008Z","steps":["trace[1751764503] 'process raft request' (duration: 219.451707ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:07.305287Z","caller":"traceutil/trace.go:171","msg":"trace[1296390140] linearizableReadLoop","detail":"{readStateIndex:24975; appliedIndex:24974; }","duration":"330.253112ms","start":"2026-05-08T19:05:06.97501Z","end":"2026-05-08T19:05:07.305264Z","steps":["trace[1296390140] 'read index received' (duration: 330.15923ms)","trace[1296390140] 'applied index is now lower than readState.Index' (duration: 92.792µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:07.305441Z","caller":"traceutil/trace.go:171","msg":"trace[942898325] transaction","detail":"{read_only:false; response_revision:24196; number_of_response:1; }","duration":"390.532645ms","start":"2026-05-08T19:05:06.914836Z","end":"2026-05-08T19:05:07.305368Z","steps":["trace[942898325] 'process raft request' (duration: 390.29085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:07.305458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.178265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:07.305623Z","caller":"traceutil/trace.go:171","msg":"trace[120534078] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24196; }","duration":"349.38841ms","start":"2026-05-08T19:05:06.956218Z","end":"2026-05-08T19:05:07.305606Z","steps":["trace[120534078] 'agreement among raft nodes before linearized reading' (duration: 349.179125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:07.305669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:06.956192Z","time spent":"349.462222ms","remote":"127.0.0.1:39246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-08T19:05:07.305636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:06.914819Z","time spent":"390.715029ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:07.349161Z","caller":"traceutil/trace.go:171","msg":"trace[144336414] transaction","detail":"{read_only:false; response_revision:24197; number_of_response:1; }","duration":"237.653953ms","start":"2026-05-08T19:05:07.111475Z","end":"2026-05-08T19:05:07.349129Z","steps":["trace[144336414] 'process raft request' (duration: 237.290875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:07.349531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.508981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-08T19:05:07.349683Z","caller":"traceutil/trace.go:171","msg":"trace[741554115] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7; range_end:; response_count:1; response_revision:24197; }","duration":"346.698576ms","start":"2026-05-08T19:05:07.002965Z","end":"2026-05-08T19:05:07.349663Z","steps":["trace[741554115] 'agreement among raft nodes before linearized reading' (duration: 346.421599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:07.349797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:07.002948Z","time spent":"346.83506ms","remote":"127.0.0.1:39012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcb67fa2-f8a5-4fb1-98bd-fc2626c6a9f7\" "} {"level":"warn","ts":"2026-05-08T19:05:07.350197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.050393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-08T19:05:07.350309Z","caller":"traceutil/trace.go:171","msg":"trace[1841698028] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24197; }","duration":"243.214427ms","start":"2026-05-08T19:05:07.107068Z","end":"2026-05-08T19:05:07.350283Z","steps":["trace[1841698028] 'agreement among raft nodes before linearized reading' (duration: 242.631873ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:07.72155Z","caller":"traceutil/trace.go:171","msg":"trace[1818015081] transaction","detail":"{read_only:false; response_revision:24198; number_of_response:1; }","duration":"329.527964ms","start":"2026-05-08T19:05:07.391992Z","end":"2026-05-08T19:05:07.72152Z","steps":["trace[1818015081] 'process raft request' (duration: 259.283062ms)","trace[1818015081] 'compare' (duration: 70.13965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:07.721686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:07.391965Z","time spent":"329.654428ms","remote":"127.0.0.1:39096","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-05-08T19:05:07.797928Z","caller":"traceutil/trace.go:171","msg":"trace[1197579956] linearizableReadLoop","detail":"{readStateIndex:24978; appliedIndex:24976; }","duration":"197.106572ms","start":"2026-05-08T19:05:07.600797Z","end":"2026-05-08T19:05:07.797903Z","steps":["trace[1197579956] 'read index received' (duration: 50.509351ms)","trace[1197579956] 'applied index is now lower than readState.Index' (duration: 146.595661ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:07.798047Z","caller":"traceutil/trace.go:171","msg":"trace[1187618076] transaction","detail":"{read_only:false; response_revision:24199; number_of_response:1; }","duration":"405.880524ms","start":"2026-05-08T19:05:07.392129Z","end":"2026-05-08T19:05:07.79801Z","steps":["trace[1187618076] 'process raft request' (duration: 405.595807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:07.798278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:07.392107Z","time spent":"406.062638ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:07.798099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.277086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:07.798357Z","caller":"traceutil/trace.go:171","msg":"trace[1615312136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24199; }","duration":"197.547753ms","start":"2026-05-08T19:05:07.600791Z","end":"2026-05-08T19:05:07.798339Z","steps":["trace[1615312136] 'agreement among raft nodes before linearized reading' (duration: 197.219055ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:07.978079Z","caller":"traceutil/trace.go:171","msg":"trace[202270802] linearizableReadLoop","detail":"{readStateIndex:24979; appliedIndex:24978; }","duration":"178.60783ms","start":"2026-05-08T19:05:07.799434Z","end":"2026-05-08T19:05:07.978042Z","steps":["trace[202270802] 'read index received' (duration: 170.446994ms)","trace[202270802] 'applied index is now lower than readState.Index' (duration: 8.148045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:07.978284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.824775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:07.978432Z","caller":"traceutil/trace.go:171","msg":"trace[1836338823] transaction","detail":"{read_only:false; response_revision:24200; number_of_response:1; }","duration":"286.851812ms","start":"2026-05-08T19:05:07.691534Z","end":"2026-05-08T19:05:07.978386Z","steps":["trace[1836338823] 'process raft request' (duration: 278.464351ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:07.978501Z","caller":"traceutil/trace.go:171","msg":"trace[1274716357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24200; }","duration":"178.953028ms","start":"2026-05-08T19:05:07.799426Z","end":"2026-05-08T19:05:07.978379Z","steps":["trace[1274716357] 'agreement among raft nodes before linearized reading' (duration: 178.744873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:08.35334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.179883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:08.353944Z","caller":"traceutil/trace.go:171","msg":"trace[2101812248] transaction","detail":"{read_only:false; response_revision:24201; number_of_response:1; }","duration":"350.373613ms","start":"2026-05-08T19:05:08.003085Z","end":"2026-05-08T19:05:08.353458Z","steps":["trace[2101812248] 'process raft request' (duration: 142.953954ms)","trace[2101812248] 'compare' (duration: 206.930887ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:08.354082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:08.003059Z","time spent":"350.972048ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:08.354515Z","caller":"traceutil/trace.go:171","msg":"trace[355661595] linearizableReadLoop","detail":"{readStateIndex:24981; appliedIndex:24979; }","duration":"315.190761ms","start":"2026-05-08T19:05:08.039303Z","end":"2026-05-08T19:05:08.354493Z","steps":["trace[355661595] 'read index received' (duration: 106.757838ms)","trace[355661595] 'applied index is now lower than readState.Index' (duration: 208.431713ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:08.354744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.438297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-08T19:05:08.35472Z","caller":"traceutil/trace.go:171","msg":"trace[1586762149] transaction","detail":"{read_only:false; response_revision:24202; number_of_response:1; }","duration":"351.494151ms","start":"2026-05-08T19:05:08.003174Z","end":"2026-05-08T19:05:08.354668Z","steps":["trace[1586762149] 'process raft request' (duration: 351.221974ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:08.354781Z","caller":"traceutil/trace.go:171","msg":"trace[410858202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24202; }","duration":"315.54099ms","start":"2026-05-08T19:05:08.039224Z","end":"2026-05-08T19:05:08.354765Z","steps":["trace[410858202] 'agreement among raft nodes before linearized reading' (duration: 315.355966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:08.354842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:08.039197Z","time spent":"315.636791ms","remote":"127.0.0.1:39096","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-05-08T19:05:08.354938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:08.003121Z","time spent":"351.695576ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:08.864742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.49641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-08T19:05:08.864818Z","caller":"traceutil/trace.go:171","msg":"trace[1515456960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24204; }","duration":"348.621103ms","start":"2026-05-08T19:05:08.516179Z","end":"2026-05-08T19:05:08.8648Z","steps":["trace[1515456960] 'range keys from in-memory index tree' (duration: 348.316945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:08.864854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:08.516154Z","time spent":"348.691954ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T19:05:08.865167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.150496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:08.865197Z","caller":"traceutil/trace.go:171","msg":"trace[1337053091] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24204; }","duration":"410.222798ms","start":"2026-05-08T19:05:08.454962Z","end":"2026-05-08T19:05:08.865185Z","steps":["trace[1337053091] 'count revisions from in-memory index tree' (duration: 410.027283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:08.86522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:08.454944Z","time spent":"410.269129ms","remote":"127.0.0.1:59244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"info","ts":"2026-05-08T19:05:08.865573Z","caller":"traceutil/trace.go:171","msg":"trace[1160073924] linearizableReadLoop","detail":"{readStateIndex:24984; appliedIndex:24983; }","duration":"264.540297ms","start":"2026-05-08T19:05:08.600987Z","end":"2026-05-08T19:05:08.865527Z","steps":["trace[1160073924] 'read index received' (duration: 251.235578ms)","trace[1160073924] 'applied index is now lower than readState.Index' (duration: 13.303199ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:08.865746Z","caller":"traceutil/trace.go:171","msg":"trace[1251123713] transaction","detail":"{read_only:false; response_revision:24205; number_of_response:1; }","duration":"349.475922ms","start":"2026-05-08T19:05:08.516234Z","end":"2026-05-08T19:05:08.86571Z","steps":["trace[1251123713] 'process raft request' (duration: 336.03825ms)","trace[1251123713] 'compare' (duration: 12.277845ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:08.865778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.794843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:05:08.86579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.700908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-08T19:05:08.865818Z","caller":"traceutil/trace.go:171","msg":"trace[1074131984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24205; }","duration":"264.862804ms","start":"2026-05-08T19:05:08.600944Z","end":"2026-05-08T19:05:08.865806Z","steps":["trace[1074131984] 'agreement among raft nodes before linearized reading' (duration: 264.748742ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:08.865889Z","caller":"traceutil/trace.go:171","msg":"trace[528512036] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24205; }","duration":"172.736449ms","start":"2026-05-08T19:05:08.69308Z","end":"2026-05-08T19:05:08.865816Z","steps":["trace[528512036] 'agreement among raft nodes before linearized reading' (duration: 172.624526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:08.865884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:08.516215Z","time spent":"349.591615ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:12.016788Z","caller":"traceutil/trace.go:171","msg":"trace[1186410404] linearizableReadLoop","detail":"{readStateIndex:25045; appliedIndex:25044; }","duration":"206.830025ms","start":"2026-05-08T19:05:11.809928Z","end":"2026-05-08T19:05:12.016758Z","steps":["trace[1186410404] 'read index received' (duration: 206.589839ms)","trace[1186410404] 'applied index is now lower than readState.Index' (duration: 238.805µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:12.016945Z","caller":"traceutil/trace.go:171","msg":"trace[187341166] transaction","detail":"{read_only:false; response_revision:24264; number_of_response:1; }","duration":"256.669849ms","start":"2026-05-08T19:05:11.760244Z","end":"2026-05-08T19:05:12.016913Z","steps":["trace[187341166] 'process raft request' (duration: 256.337101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:12.017184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.237845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-08T19:05:12.017235Z","caller":"traceutil/trace.go:171","msg":"trace[806798868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24264; }","duration":"207.338916ms","start":"2026-05-08T19:05:11.809883Z","end":"2026-05-08T19:05:12.017222Z","steps":["trace[806798868] 'agreement among raft nodes before linearized reading' (duration: 207.161802ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:12.138526Z","caller":"traceutil/trace.go:171","msg":"trace[1704198602] transaction","detail":"{read_only:false; response_revision:24265; number_of_response:1; }","duration":"276.819501ms","start":"2026-05-08T19:05:11.861681Z","end":"2026-05-08T19:05:12.1385Z","steps":["trace[1704198602] 'process raft request' (duration: 265.226163ms)","trace[1704198602] 'compare' (duration: 11.349832ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:12.138556Z","caller":"traceutil/trace.go:171","msg":"trace[676208343] linearizableReadLoop","detail":"{readStateIndex:25047; appliedIndex:25045; }","duration":"121.615794ms","start":"2026-05-08T19:05:12.016922Z","end":"2026-05-08T19:05:12.138538Z","steps":["trace[676208343] 'read index received' (duration: 109.970155ms)","trace[676208343] 'applied index is now lower than readState.Index' (duration: 11.644619ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:12.138632Z","caller":"traceutil/trace.go:171","msg":"trace[1657339354] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"135.046996ms","start":"2026-05-08T19:05:12.003578Z","end":"2026-05-08T19:05:12.138625Z","steps":["trace[1657339354] 'process raft request' (duration: 134.866581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:12.138675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.23124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:05:12.1387Z","caller":"traceutil/trace.go:171","msg":"trace[1259629318] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24266; }","duration":"158.306031ms","start":"2026-05-08T19:05:11.980388Z","end":"2026-05-08T19:05:12.138694Z","steps":["trace[1259629318] 'agreement among raft nodes before linearized reading' (duration: 158.24001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:12.13881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.762488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-08T19:05:12.138891Z","caller":"traceutil/trace.go:171","msg":"trace[558358063] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24266; }","duration":"197.8853ms","start":"2026-05-08T19:05:11.940986Z","end":"2026-05-08T19:05:12.138872Z","steps":["trace[558358063] 'agreement among raft nodes before linearized reading' (duration: 197.693175ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:12.150242Z","caller":"traceutil/trace.go:171","msg":"trace[268714693] transaction","detail":"{read_only:false; response_revision:24267; number_of_response:1; }","duration":"128.748044ms","start":"2026-05-08T19:05:12.021471Z","end":"2026-05-08T19:05:12.150219Z","steps":["trace[268714693] 'process raft request' (duration: 127.039873ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:13.693836Z","caller":"traceutil/trace.go:171","msg":"trace[408441196] transaction","detail":"{read_only:false; response_revision:24296; number_of_response:1; }","duration":"120.999329ms","start":"2026-05-08T19:05:13.572806Z","end":"2026-05-08T19:05:13.693805Z","steps":["trace[408441196] 'process raft request' (duration: 120.594489ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:14.299832Z","caller":"traceutil/trace.go:171","msg":"trace[973569282] transaction","detail":"{read_only:false; response_revision:24312; number_of_response:1; }","duration":"141.575432ms","start":"2026-05-08T19:05:14.158239Z","end":"2026-05-08T19:05:14.299814Z","steps":["trace[973569282] 'process raft request' (duration: 141.465119ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:44.567355Z","caller":"traceutil/trace.go:171","msg":"trace[132679134] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"123.430627ms","start":"2026-05-08T19:05:44.4439Z","end":"2026-05-08T19:05:44.56733Z","steps":["trace[132679134] 'process raft request' (duration: 82.179909ms)","trace[132679134] 'compare' (duration: 41.153385ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:44.817534Z","caller":"traceutil/trace.go:171","msg":"trace[254538286] linearizableReadLoop","detail":"{readStateIndex:25359; appliedIndex:25356; }","duration":"226.837994ms","start":"2026-05-08T19:05:44.590676Z","end":"2026-05-08T19:05:44.817514Z","steps":["trace[254538286] 'read index received' (duration: 142.561415ms)","trace[254538286] 'applied index is now lower than readState.Index' (duration: 84.275469ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:44.817631Z","caller":"traceutil/trace.go:171","msg":"trace[1321318167] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"338.751126ms","start":"2026-05-08T19:05:44.478844Z","end":"2026-05-08T19:05:44.817596Z","steps":["trace[1321318167] 'process raft request' (duration: 338.430048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:44.817793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.478824Z","time spent":"338.887799ms","remote":"127.0.0.1:39096","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-05-08T19:05:44.817866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.382464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-08T19:05:44.817869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.659657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-08T19:05:44.817924Z","caller":"traceutil/trace.go:171","msg":"trace[1209445720] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:24571; }","duration":"110.489667ms","start":"2026-05-08T19:05:44.707422Z","end":"2026-05-08T19:05:44.817912Z","steps":["trace[1209445720] 'agreement among raft nodes before linearized reading' (duration: 110.356904ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:44.817968Z","caller":"traceutil/trace.go:171","msg":"trace[273485440] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24571; }","duration":"215.79083ms","start":"2026-05-08T19:05:44.602155Z","end":"2026-05-08T19:05:44.817946Z","steps":["trace[273485440] 'agreement among raft nodes before linearized reading' (duration: 215.612756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:44.817978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.290785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-08T19:05:44.818057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.637949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-08T19:05:44.818107Z","caller":"traceutil/trace.go:171","msg":"trace[309948179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24571; }","duration":"203.704511ms","start":"2026-05-08T19:05:44.61439Z","end":"2026-05-08T19:05:44.818094Z","steps":["trace[309948179] 'agreement among raft nodes before linearized reading' (duration: 203.550207ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:44.81811Z","caller":"traceutil/trace.go:171","msg":"trace[1347682500] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24571; }","duration":"227.46546ms","start":"2026-05-08T19:05:44.590629Z","end":"2026-05-08T19:05:44.818095Z","steps":["trace[1347682500] 'agreement among raft nodes before linearized reading' (duration: 227.003799ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:44.817803Z","caller":"traceutil/trace.go:171","msg":"trace[1952099166] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"338.670124ms","start":"2026-05-08T19:05:44.478952Z","end":"2026-05-08T19:05:44.817622Z","steps":["trace[1952099166] 'process raft request' (duration: 338.50513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:44.818238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.478918Z","time spent":"339.242927ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:47.042659Z","caller":"traceutil/trace.go:171","msg":"trace[607738691] linearizableReadLoop","detail":"{readStateIndex:25374; appliedIndex:25372; }","duration":"216.079596ms","start":"2026-05-08T19:05:46.826558Z","end":"2026-05-08T19:05:47.042638Z","steps":["trace[607738691] 'read index received' (duration: 79.988266ms)","trace[607738691] 'applied index is now lower than readState.Index' (duration: 136.09039ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:47.042724Z","caller":"traceutil/trace.go:171","msg":"trace[207637660] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"218.956755ms","start":"2026-05-08T19:05:46.823728Z","end":"2026-05-08T19:05:47.042685Z","steps":["trace[207637660] 'process raft request' (duration: 218.73375ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:47.042773Z","caller":"traceutil/trace.go:171","msg":"trace[1964886715] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"143.956059ms","start":"2026-05-08T19:05:46.898788Z","end":"2026-05-08T19:05:47.042744Z","steps":["trace[1964886715] 'process raft request' (duration: 143.803315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.042849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.280882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-08T19:05:47.042888Z","caller":"traceutil/trace.go:171","msg":"trace[1632307673] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24587; }","duration":"216.357603ms","start":"2026-05-08T19:05:46.826521Z","end":"2026-05-08T19:05:47.042878Z","steps":["trace[1632307673] 'agreement among raft nodes before linearized reading' (duration: 216.22322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.068154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.468615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-08T19:05:47.068236Z","caller":"traceutil/trace.go:171","msg":"trace[270662049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24589; }","duration":"241.563007ms","start":"2026-05-08T19:05:46.826659Z","end":"2026-05-08T19:05:47.068222Z","steps":["trace[270662049] 'agreement among raft nodes before linearized reading' (duration: 241.353772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.330449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.441484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:47.330551Z","caller":"traceutil/trace.go:171","msg":"trace[824254710] linearizableReadLoop","detail":"{readStateIndex:25378; appliedIndex:25377; }","duration":"262.54826ms","start":"2026-05-08T19:05:47.067987Z","end":"2026-05-08T19:05:47.330535Z","steps":["trace[824254710] 'read index received' (duration: 129.949762ms)","trace[824254710] 'applied index is now lower than readState.Index' (duration: 132.597507ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:47.330699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.11791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-08T19:05:47.330676Z","caller":"traceutil/trace.go:171","msg":"trace[420311595] transaction","detail":"{read_only:false; response_revision:24590; number_of_response:1; }","duration":"283.052501ms","start":"2026-05-08T19:05:47.047588Z","end":"2026-05-08T19:05:47.330641Z","steps":["trace[420311595] 'process raft request' (duration: 150.344411ms)","trace[420311595] 'compare' (duration: 132.320361ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:47.330739Z","caller":"traceutil/trace.go:171","msg":"trace[1995271880] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24590; }","duration":"275.187312ms","start":"2026-05-08T19:05:47.055541Z","end":"2026-05-08T19:05:47.330729Z","steps":["trace[1995271880] 'agreement among raft nodes before linearized reading' (duration: 275.038729ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:47.596441Z","caller":"traceutil/trace.go:171","msg":"trace[495258188] transaction","detail":"{read_only:false; response_revision:24592; number_of_response:1; }","duration":"478.179416ms","start":"2026-05-08T19:05:47.118235Z","end":"2026-05-08T19:05:47.596414Z","steps":["trace[495258188] 'process raft request' (duration: 478.104664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.596589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:47.118215Z","time spent":"478.296108ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:47.596833Z","caller":"traceutil/trace.go:171","msg":"trace[918385353] transaction","detail":"{read_only:false; response_revision:24591; number_of_response:1; }","duration":"525.047898ms","start":"2026-05-08T19:05:47.071764Z","end":"2026-05-08T19:05:47.596812Z","steps":["trace[918385353] 'process raft request' (duration: 524.439194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.596977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:47.071741Z","time spent":"525.182662ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:47.610367Z","caller":"traceutil/trace.go:171","msg":"trace[2080799986] linearizableReadLoop","detail":"{readStateIndex:25381; appliedIndex:25378; }","duration":"232.418808ms","start":"2026-05-08T19:05:47.377926Z","end":"2026-05-08T19:05:47.610345Z","steps":["trace[2080799986] 'read index received' (duration: 218.243218ms)","trace[2080799986] 'applied index is now lower than readState.Index' (duration: 14.17372ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:47.610402Z","caller":"traceutil/trace.go:171","msg":"trace[1794029775] transaction","detail":"{read_only:false; response_revision:24593; number_of_response:1; }","duration":"275.916921ms","start":"2026-05-08T19:05:47.334467Z","end":"2026-05-08T19:05:47.610384Z","steps":["trace[1794029775] 'process raft request' (duration: 275.694916ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:47.610491Z","caller":"traceutil/trace.go:171","msg":"trace[897227529] transaction","detail":"{read_only:false; response_revision:24594; number_of_response:1; }","duration":"120.527777ms","start":"2026-05-08T19:05:47.489952Z","end":"2026-05-08T19:05:47.61048Z","steps":["trace[897227529] 'process raft request' (duration: 120.343163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.610573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.614986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:05:47.610607Z","caller":"traceutil/trace.go:171","msg":"trace[880155995] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24594; }","duration":"119.677727ms","start":"2026-05-08T19:05:47.490918Z","end":"2026-05-08T19:05:47.610596Z","steps":["trace[880155995] 'agreement among raft nodes before linearized reading' (duration: 119.578424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.610688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.412251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:47.610719Z","caller":"traceutil/trace.go:171","msg":"trace[1288210288] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:24594; }","duration":"109.472293ms","start":"2026-05-08T19:05:47.501239Z","end":"2026-05-08T19:05:47.610711Z","steps":["trace[1288210288] 'agreement among raft nodes before linearized reading' (duration: 109.422261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.610738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.810068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-05-08T19:05:47.61076Z","caller":"traceutil/trace.go:171","msg":"trace[355742740] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24594; }","duration":"232.868058ms","start":"2026-05-08T19:05:47.377884Z","end":"2026-05-08T19:05:47.610752Z","steps":["trace[355742740] 'agreement among raft nodes before linearized reading' (duration: 232.779746ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:47.751688Z","caller":"traceutil/trace.go:171","msg":"trace[778357185] linearizableReadLoop","detail":"{readStateIndex:25383; appliedIndex:25382; }","duration":"136.779827ms","start":"2026-05-08T19:05:47.614885Z","end":"2026-05-08T19:05:47.751665Z","steps":["trace[778357185] 'read index received' (duration: 131.464169ms)","trace[778357185] 'applied index is now lower than readState.Index' (duration: 5.314718ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:47.751761Z","caller":"traceutil/trace.go:171","msg":"trace[763232267] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"136.892599ms","start":"2026-05-08T19:05:47.614831Z","end":"2026-05-08T19:05:47.751723Z","steps":["trace[763232267] 'process raft request' (duration: 131.462529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:47.751877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.968661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-05-08T19:05:47.751953Z","caller":"traceutil/trace.go:171","msg":"trace[698415420] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24595; }","duration":"137.069513ms","start":"2026-05-08T19:05:47.614866Z","end":"2026-05-08T19:05:47.751936Z","steps":["trace[698415420] 'agreement among raft nodes before linearized reading' (duration: 136.871739ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:48.870059Z","caller":"traceutil/trace.go:171","msg":"trace[235897724] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"230.619224ms","start":"2026-05-08T19:05:48.639353Z","end":"2026-05-08T19:05:48.869972Z","steps":["trace[235897724] 'process raft request' (duration: 230.154333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.435533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.358005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:49.435815Z","caller":"traceutil/trace.go:171","msg":"trace[1911425089] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"388.881557ms","start":"2026-05-08T19:05:49.046915Z","end":"2026-05-08T19:05:49.435797Z","steps":["trace[1911425089] 'process raft request' (duration: 237.166853ms)","trace[1911425089] 'compare' (duration: 151.103599ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:49.435811Z","caller":"traceutil/trace.go:171","msg":"trace[1883672719] transaction","detail":"{read_only:false; response_revision:24602; number_of_response:1; }","duration":"362.490733ms","start":"2026-05-08T19:05:49.073288Z","end":"2026-05-08T19:05:49.435779Z","steps":["trace[1883672719] 'process raft request' (duration: 362.34366ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:49.435821Z","caller":"traceutil/trace.go:171","msg":"trace[2022995031] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"362.178726ms","start":"2026-05-08T19:05:49.073618Z","end":"2026-05-08T19:05:49.435797Z","steps":["trace[2022995031] 'process raft request' (duration: 362.086023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.43591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.073598Z","time spent":"362.276918ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:49.435929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.046894Z","time spent":"388.968178ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:49.435929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.073263Z","time spent":"362.604916ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:49.43592Z","caller":"traceutil/trace.go:171","msg":"trace[1883748006] linearizableReadLoop","detail":"{readStateIndex:25393; appliedIndex:25389; }","duration":"198.437374ms","start":"2026-05-08T19:05:49.237455Z","end":"2026-05-08T19:05:49.435893Z","steps":["trace[1883748006] 'read index received' (duration: 46.645928ms)","trace[1883748006] 'applied index is now lower than readState.Index' (duration: 151.789656ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:49.43595Z","caller":"traceutil/trace.go:171","msg":"trace[172153513] transaction","detail":"{read_only:false; response_revision:24604; number_of_response:1; }","duration":"304.691578ms","start":"2026-05-08T19:05:49.131228Z","end":"2026-05-08T19:05:49.435919Z","steps":["trace[172153513] 'process raft request' (duration: 304.519814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.436057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.598997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:49.436102Z","caller":"traceutil/trace.go:171","msg":"trace[106817812] 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:24604; }","duration":"198.67173ms","start":"2026-05-08T19:05:49.237419Z","end":"2026-05-08T19:05:49.436091Z","steps":["trace[106817812] 'agreement among raft nodes before linearized reading' (duration: 198.592168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.436122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.131207Z","time spent":"304.821551ms","remote":"127.0.0.1:38832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:49.436168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.022744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-08T19:05:49.436206Z","caller":"traceutil/trace.go:171","msg":"trace[590426007] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24604; }","duration":"120.098346ms","start":"2026-05-08T19:05:49.316098Z","end":"2026-05-08T19:05:49.436196Z","steps":["trace[590426007] 'agreement among raft nodes before linearized reading' (duration: 119.948402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.436207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.825409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-08T19:05:49.436246Z","caller":"traceutil/trace.go:171","msg":"trace[1711171617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24604; }","duration":"101.922881ms","start":"2026-05-08T19:05:49.334312Z","end":"2026-05-08T19:05:49.436235Z","steps":["trace[1711171617] 'agreement among raft nodes before linearized reading' (duration: 101.738327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.696544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.157488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:49.696651Z","caller":"traceutil/trace.go:171","msg":"trace[1995547348] linearizableReadLoop","detail":"{readStateIndex:25394; appliedIndex:25393; }","duration":"255.41672ms","start":"2026-05-08T19:05:49.441215Z","end":"2026-05-08T19:05:49.696632Z","steps":["trace[1995547348] 'read index received' (duration: 125.068127ms)","trace[1995547348] 'applied index is now lower than readState.Index' (duration: 130.347213ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:49.696721Z","caller":"traceutil/trace.go:171","msg":"trace[273694479] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"256.32974ms","start":"2026-05-08T19:05:49.440379Z","end":"2026-05-08T19:05:49.696709Z","steps":["trace[273694479] 'process raft request' (duration: 125.899966ms)","trace[273694479] 'compare' (duration: 129.981444ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:49.697093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.763998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-08T19:05:49.697164Z","caller":"traceutil/trace.go:171","msg":"trace[161048131] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24605; }","duration":"255.950953ms","start":"2026-05-08T19:05:49.441199Z","end":"2026-05-08T19:05:49.69715Z","steps":["trace[161048131] 'agreement among raft nodes before linearized reading' (duration: 255.525422ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:49.782178Z","caller":"traceutil/trace.go:171","msg":"trace[193310243] transaction","detail":"{read_only:false; response_revision:24606; number_of_response:1; }","duration":"175.229919ms","start":"2026-05-08T19:05:49.606918Z","end":"2026-05-08T19:05:49.782148Z","steps":["trace[193310243] 'process raft request' (duration: 175.045894ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:49.969356Z","caller":"traceutil/trace.go:171","msg":"trace[1439971368] linearizableReadLoop","detail":"{readStateIndex:25396; appliedIndex:25394; }","duration":"272.657192ms","start":"2026-05-08T19:05:49.696677Z","end":"2026-05-08T19:05:49.969334Z","steps":["trace[1439971368] 'read index received' (duration: 85.349335ms)","trace[1439971368] 'applied index is now lower than readState.Index' (duration: 187.306937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:49.969539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.668912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:05:49.969543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.866139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:49.969589Z","caller":"traceutil/trace.go:171","msg":"trace[81727839] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24607; }","duration":"527.752684ms","start":"2026-05-08T19:05:49.441821Z","end":"2026-05-08T19:05:49.969574Z","steps":["trace[81727839] 'agreement among raft nodes before linearized reading' (duration: 527.663932ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:49.969596Z","caller":"traceutil/trace.go:171","msg":"trace[910340031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24607; }","duration":"366.961841ms","start":"2026-05-08T19:05:49.602622Z","end":"2026-05-08T19:05:49.969584Z","steps":["trace[910340031] 'agreement among raft nodes before linearized reading' (duration: 366.859229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.969585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.910253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-08T19:05:49.96963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.602606Z","time spent":"367.019843ms","remote":"127.0.0.1:45302","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-05-08T19:05:49.969629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.44181Z","time spent":"527.810055ms","remote":"127.0.0.1:39026","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-05-08T19:05:49.969652Z","caller":"traceutil/trace.go:171","msg":"trace[1024015006] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24607; }","duration":"355.015475ms","start":"2026-05-08T19:05:49.61462Z","end":"2026-05-08T19:05:49.969636Z","steps":["trace[1024015006] 'agreement among raft nodes before linearized reading' (duration: 354.78377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.969631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.376359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-08T19:05:49.96969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.614599Z","time spent":"355.083767ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T19:05:49.969762Z","caller":"traceutil/trace.go:171","msg":"trace[997598933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24607; }","duration":"364.532453ms","start":"2026-05-08T19:05:49.605207Z","end":"2026-05-08T19:05:49.969739Z","steps":["trace[997598933] 'agreement among raft nodes before linearized reading' (duration: 364.223135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.969809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.605198Z","time spent":"364.602355ms","remote":"127.0.0.1:39096","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":"info","ts":"2026-05-08T19:05:49.969637Z","caller":"traceutil/trace.go:171","msg":"trace[698221354] transaction","detail":"{read_only:false; response_revision:24607; number_of_response:1; }","duration":"352.904414ms","start":"2026-05-08T19:05:49.616695Z","end":"2026-05-08T19:05:49.9696Z","steps":["trace[698221354] 'process raft request' (duration: 352.472924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.969889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.035838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:05:49.969957Z","caller":"traceutil/trace.go:171","msg":"trace[956330730] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24607; }","duration":"214.151001ms","start":"2026-05-08T19:05:49.755791Z","end":"2026-05-08T19:05:49.969942Z","steps":["trace[956330730] 'agreement among raft nodes before linearized reading' (duration: 213.859854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:49.969972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:49.616675Z","time spent":"353.203792ms","remote":"127.0.0.1:39096","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-05-08T19:05:49.971062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.32829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-08T19:05:49.971133Z","caller":"traceutil/trace.go:171","msg":"trace[823129037] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24607; }","duration":"102.407162ms","start":"2026-05-08T19:05:49.868713Z","end":"2026-05-08T19:05:49.97112Z","steps":["trace[823129037] 'agreement among raft nodes before linearized reading' (duration: 101.014479ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:50.115605Z","caller":"traceutil/trace.go:171","msg":"trace[1668525860] transaction","detail":"{read_only:false; response_revision:24608; number_of_response:1; }","duration":"141.586792ms","start":"2026-05-08T19:05:49.973991Z","end":"2026-05-08T19:05:50.115578Z","steps":["trace[1668525860] 'process raft request' (duration: 78.935652ms)","trace[1668525860] 'compare' (duration: 62.246421ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:50.115734Z","caller":"traceutil/trace.go:171","msg":"trace[934143105] transaction","detail":"{read_only:false; response_revision:24609; number_of_response:1; }","duration":"138.260562ms","start":"2026-05-08T19:05:49.977463Z","end":"2026-05-08T19:05:50.115724Z","steps":["trace[934143105] 'process raft request' (duration: 138.061527ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:50.115855Z","caller":"traceutil/trace.go:171","msg":"trace[1952230410] transaction","detail":"{read_only:false; response_revision:24610; number_of_response:1; }","duration":"138.337474ms","start":"2026-05-08T19:05:49.977506Z","end":"2026-05-08T19:05:50.115844Z","steps":["trace[1952230410] 'process raft request' (duration: 138.1826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:50.115917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.674174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-05-08T19:05:50.115973Z","caller":"traceutil/trace.go:171","msg":"trace[483995116] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24610; }","duration":"141.766957ms","start":"2026-05-08T19:05:49.974196Z","end":"2026-05-08T19:05:50.115963Z","steps":["trace[483995116] 'agreement among raft nodes before linearized reading' (duration: 141.458709ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:50.115614Z","caller":"traceutil/trace.go:171","msg":"trace[1819211217] linearizableReadLoop","detail":"{readStateIndex:25397; appliedIndex:25396; }","duration":"141.361327ms","start":"2026-05-08T19:05:49.974235Z","end":"2026-05-08T19:05:50.115597Z","steps":["trace[1819211217] 'read index received' (duration: 78.820129ms)","trace[1819211217] 'applied index is now lower than readState.Index' (duration: 62.540228ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:50.116186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.316548ms","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-05-08T19:05:50.116243Z","caller":"traceutil/trace.go:171","msg":"trace[127630307] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24610; }","duration":"139.39159ms","start":"2026-05-08T19:05:49.976838Z","end":"2026-05-08T19:05:50.116229Z","steps":["trace[127630307] 'agreement among raft nodes before linearized reading' (duration: 139.277177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:50.445377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.429047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:50.445486Z","caller":"traceutil/trace.go:171","msg":"trace[1861433007] transaction","detail":"{read_only:false; response_revision:24611; number_of_response:1; }","duration":"244.338475ms","start":"2026-05-08T19:05:50.201128Z","end":"2026-05-08T19:05:50.445466Z","steps":["trace[1861433007] 'process raft request' (duration: 100.741634ms)","trace[1861433007] 'compare' (duration: 143.213572ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:50.719228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.286112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:50.719508Z","caller":"traceutil/trace.go:171","msg":"trace[1746535198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24612; }","duration":"115.56866ms","start":"2026-05-08T19:05:50.603878Z","end":"2026-05-08T19:05:50.719447Z","steps":["trace[1746535198] 'range keys from in-memory index tree' (duration: 115.198289ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:51.086081Z","caller":"traceutil/trace.go:171","msg":"trace[970602158] transaction","detail":"{read_only:false; response_revision:24613; number_of_response:1; }","duration":"312.397383ms","start":"2026-05-08T19:05:50.773659Z","end":"2026-05-08T19:05:51.086056Z","steps":["trace[970602158] 'process raft request' (duration: 312.24153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:51.086231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:50.773636Z","time spent":"312.520197ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:51.086366Z","caller":"traceutil/trace.go:171","msg":"trace[1622682131] linearizableReadLoop","detail":"{readStateIndex:25402; appliedIndex:25402; }","duration":"255.716155ms","start":"2026-05-08T19:05:50.830641Z","end":"2026-05-08T19:05:51.086357Z","steps":["trace[1622682131] 'read index received' (duration: 255.711035ms)","trace[1622682131] 'applied index is now lower than readState.Index' (duration: 4.5µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:51.086572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.904822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:05:51.086647Z","caller":"traceutil/trace.go:171","msg":"trace[1014866268] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24613; }","duration":"255.998654ms","start":"2026-05-08T19:05:50.830631Z","end":"2026-05-08T19:05:51.08663Z","steps":["trace[1014866268] 'agreement among raft nodes before linearized reading' (duration: 255.782319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:51.103507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.309264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-08T19:05:51.103603Z","caller":"traceutil/trace.go:171","msg":"trace[717827137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24614; }","duration":"229.390766ms","start":"2026-05-08T19:05:50.874176Z","end":"2026-05-08T19:05:51.103567Z","steps":["trace[717827137] 'agreement among raft nodes before linearized reading' (duration: 229.188671ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:51.103497Z","caller":"traceutil/trace.go:171","msg":"trace[363166155] transaction","detail":"{read_only:false; response_revision:24614; number_of_response:1; }","duration":"143.9884ms","start":"2026-05-08T19:05:50.959482Z","end":"2026-05-08T19:05:51.10347Z","steps":["trace[363166155] 'process raft request' (duration: 143.723883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:51.605943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.773828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:51.606016Z","caller":"traceutil/trace.go:171","msg":"trace[739723737] linearizableReadLoop","detail":"{readStateIndex:25404; appliedIndex:25403; }","duration":"291.102764ms","start":"2026-05-08T19:05:51.314901Z","end":"2026-05-08T19:05:51.606003Z","steps":["trace[739723737] 'read index received' (duration: 26.151µs)","trace[739723737] 'applied index is now lower than readState.Index' (duration: 291.075703ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:51.606056Z","caller":"traceutil/trace.go:171","msg":"trace[2041414106] transaction","detail":"{read_only:false; response_revision:24615; number_of_response:1; }","duration":"497.777696ms","start":"2026-05-08T19:05:51.108259Z","end":"2026-05-08T19:05:51.606037Z","steps":["trace[2041414106] 'process raft request' (duration: 193.823154ms)","trace[2041414106] 'compare' (duration: 303.602103ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:51.60611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.209466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-08T19:05:51.606136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:51.108235Z","time spent":"497.860287ms","remote":"127.0.0.1:39096","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-05-08T19:05:51.606152Z","caller":"traceutil/trace.go:171","msg":"trace[1633754176] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24615; }","duration":"291.282868ms","start":"2026-05-08T19:05:51.314858Z","end":"2026-05-08T19:05:51.606141Z","steps":["trace[1633754176] 'agreement among raft nodes before linearized reading' (duration: 291.184856ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:51.673892Z","caller":"traceutil/trace.go:171","msg":"trace[689460427] transaction","detail":"{read_only:false; response_revision:24616; number_of_response:1; }","duration":"231.814033ms","start":"2026-05-08T19:05:51.442049Z","end":"2026-05-08T19:05:51.673863Z","steps":["trace[689460427] 'process raft request' (duration: 231.66407ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:52.092835Z","caller":"traceutil/trace.go:171","msg":"trace[504626355] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"650.677618ms","start":"2026-05-08T19:05:51.44213Z","end":"2026-05-08T19:05:52.092807Z","steps":["trace[504626355] 'process raft request' (duration: 606.203933ms)","trace[504626355] 'compare' (duration: 44.067245ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:52.093001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:51.442103Z","time spent":"650.830502ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:52.093118Z","caller":"traceutil/trace.go:171","msg":"trace[1781985983] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"650.931524ms","start":"2026-05-08T19:05:51.442176Z","end":"2026-05-08T19:05:52.093108Z","steps":["trace[1781985983] 'process raft request' (duration: 650.574256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:52.093171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:51.442155Z","time spent":"650.988966ms","remote":"127.0.0.1:39096","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-05-08T19:05:52.093277Z","caller":"traceutil/trace.go:171","msg":"trace[814008276] linearizableReadLoop","detail":"{readStateIndex:25407; appliedIndex:25405; }","duration":"487.223592ms","start":"2026-05-08T19:05:51.606034Z","end":"2026-05-08T19:05:52.093258Z","steps":["trace[814008276] 'read index received' (duration: 68.437169ms)","trace[814008276] 'applied index is now lower than readState.Index' (duration: 418.785103ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:52.093431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.111194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:52.093472Z","caller":"traceutil/trace.go:171","msg":"trace[1287188140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24619; }","duration":"491.182706ms","start":"2026-05-08T19:05:51.602277Z","end":"2026-05-08T19:05:52.09346Z","steps":["trace[1287188140] 'agreement among raft nodes before linearized reading' (duration: 491.106875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:52.093503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:51.602266Z","time spent":"491.230917ms","remote":"127.0.0.1:45294","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-05-08T19:05:52.093502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.159263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:52.093546Z","caller":"traceutil/trace.go:171","msg":"trace[6708735] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24620; }","duration":"513.247485ms","start":"2026-05-08T19:05:51.580286Z","end":"2026-05-08T19:05:52.093533Z","steps":["trace[6708735] 'agreement among raft nodes before linearized reading' (duration: 513.161183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:52.093586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:51.580263Z","time spent":"513.315337ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-05-08T19:05:52.093577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.549293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-08T19:05:52.093734Z","caller":"traceutil/trace.go:171","msg":"trace[1101342206] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24620; }","duration":"392.736669ms","start":"2026-05-08T19:05:51.700989Z","end":"2026-05-08T19:05:52.093726Z","steps":["trace[1101342206] 'agreement among raft nodes before linearized reading' (duration: 392.493893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:52.093768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:51.700965Z","time spent":"392.79555ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T19:05:52.094125Z","caller":"traceutil/trace.go:171","msg":"trace[1583368477] transaction","detail":"{read_only:false; response_revision:24620; number_of_response:1; }","duration":"117.568917ms","start":"2026-05-08T19:05:51.976545Z","end":"2026-05-08T19:05:52.094114Z","steps":["trace[1583368477] 'process raft request' (duration: 116.44168ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:52.094325Z","caller":"traceutil/trace.go:171","msg":"trace[357441293] transaction","detail":"{read_only:false; response_revision:24619; number_of_response:1; }","duration":"304.675469ms","start":"2026-05-08T19:05:51.788935Z","end":"2026-05-08T19:05:52.09361Z","steps":["trace[357441293] 'process raft request' (duration: 304.017034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:52.094562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:51.788918Z","time spent":"305.437057ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:52.672977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.946813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:52.673113Z","caller":"traceutil/trace.go:171","msg":"trace[1463467769] transaction","detail":"{read_only:false; response_revision:24622; number_of_response:1; }","duration":"221.66172ms","start":"2026-05-08T19:05:52.451438Z","end":"2026-05-08T19:05:52.6731Z","steps":["trace[1463467769] 'process raft request' (duration: 221.620249ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:52.673105Z","caller":"traceutil/trace.go:171","msg":"trace[795795667] transaction","detail":"{read_only:false; response_revision:24621; number_of_response:1; }","duration":"571.84634ms","start":"2026-05-08T19:05:52.101205Z","end":"2026-05-08T19:05:52.673051Z","steps":["trace[795795667] 'process raft request' (duration: 191.756184ms)","trace[795795667] 'compare' (duration: 379.83495ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:52.673195Z","caller":"traceutil/trace.go:171","msg":"trace[2063029681] linearizableReadLoop","detail":"{readStateIndex:25410; appliedIndex:25409; }","duration":"553.552142ms","start":"2026-05-08T19:05:52.119603Z","end":"2026-05-08T19:05:52.673156Z","steps":["trace[2063029681] 'read index received' (duration: 173.395194ms)","trace[2063029681] 'applied index is now lower than readState.Index' (duration: 380.149577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:52.673258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.101182Z","time spent":"571.991793ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:52.673886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.258809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-08T19:05:52.674781Z","caller":"traceutil/trace.go:171","msg":"trace[472529067] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24622; }","duration":"554.401392ms","start":"2026-05-08T19:05:52.119557Z","end":"2026-05-08T19:05:52.673958Z","steps":["trace[472529067] 'agreement among raft nodes before linearized reading' (duration: 553.753967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:52.674871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.119529Z","time spent":"555.320844ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T19:05:53.173848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976532254291713936,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T19:05:53.226462Z","caller":"traceutil/trace.go:171","msg":"trace[1012184738] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"752.367394ms","start":"2026-05-08T19:05:52.474075Z","end":"2026-05-08T19:05:53.226442Z","steps":["trace[1012184738] 'process raft request' (duration: 752.087727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.226599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.474052Z","time spent":"752.469756ms","remote":"127.0.0.1:54032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:53.666623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.835378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:53.666818Z","caller":"traceutil/trace.go:171","msg":"trace[2120215756] linearizableReadLoop","detail":"{readStateIndex:25414; appliedIndex:25412; }","duration":"993.524612ms","start":"2026-05-08T19:05:52.673277Z","end":"2026-05-08T19:05:53.666801Z","steps":["trace[2120215756] 'read index received' (duration: 559.654878ms)","trace[2120215756] 'applied index is now lower than readState.Index' (duration: 433.868814ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:53.666904Z","caller":"traceutil/trace.go:171","msg":"trace[1777513308] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"977.563899ms","start":"2026-05-08T19:05:52.689329Z","end":"2026-05-08T19:05:53.666893Z","steps":["trace[1777513308] 'process raft request' (duration: 580.376503ms)","trace[1777513308] 'compare' (duration: 396.681423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:53.666965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.689304Z","time spent":"977.629331ms","remote":"127.0.0.1:39096","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-05-08T19:05:53.667245Z","caller":"traceutil/trace.go:171","msg":"trace[430519005] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"557.410553ms","start":"2026-05-08T19:05:53.109823Z","end":"2026-05-08T19:05:53.667234Z","steps":["trace[430519005] 'process raft request' (duration: 556.911552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.667307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:53.1098Z","time spent":"557.477875ms","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:53.66745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064773058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:53.667486Z","caller":"traceutil/trace.go:171","msg":"trace[629105683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24625; }","duration":"1.06483137s","start":"2026-05-08T19:05:52.602646Z","end":"2026-05-08T19:05:53.667477Z","steps":["trace[629105683] 'agreement among raft nodes before linearized reading' (duration: 1.064774589s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.66752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.60263Z","time spent":"1.064881611s","remote":"127.0.0.1:45294","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-05-08T19:05:53.667634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.347215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-08T19:05:53.667705Z","caller":"traceutil/trace.go:171","msg":"trace[610677378] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24625; }","duration":"239.444997ms","start":"2026-05-08T19:05:53.428238Z","end":"2026-05-08T19:05:53.667683Z","steps":["trace[610677378] 'agreement among raft nodes before linearized reading' (duration: 239.282693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.66772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447737713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:05:53.667744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218360798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T19:05:53.667751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547439961s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-08T19:05:53.667797Z","caller":"traceutil/trace.go:171","msg":"trace[783997998] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24625; }","duration":"1.447860006s","start":"2026-05-08T19:05:52.219916Z","end":"2026-05-08T19:05:53.667776Z","steps":["trace[783997998] 'agreement among raft nodes before linearized reading' (duration: 1.447741883s)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:53.667803Z","caller":"traceutil/trace.go:171","msg":"trace[1006409406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24625; }","duration":"1.21845437s","start":"2026-05-08T19:05:52.449336Z","end":"2026-05-08T19:05:53.667791Z","steps":["trace[1006409406] 'agreement among raft nodes before linearized reading' (duration: 1.218306637s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.66782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547699458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-08T19:05:53.667826Z","caller":"traceutil/trace.go:171","msg":"trace[766591434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24625; }","duration":"1.547538954s","start":"2026-05-08T19:05:52.12027Z","end":"2026-05-08T19:05:53.667809Z","steps":["trace[766591434] 'agreement among raft nodes before linearized reading' (duration: 1.547317129s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.667847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.449316Z","time spent":"1.218524162s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T19:05:53.667852Z","caller":"traceutil/trace.go:171","msg":"trace[1857791011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24625; }","duration":"1.54776112s","start":"2026-05-08T19:05:52.120082Z","end":"2026-05-08T19:05:53.667843Z","steps":["trace[1857791011] 'agreement among raft nodes before linearized reading' (duration: 1.547665447s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.667842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.79563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:05:53.667854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.219893Z","time spent":"1.447947588s","remote":"127.0.0.1:54082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-05-08T19:05:53.667875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.120265Z","time spent":"1.547601585s","remote":"127.0.0.1:39096","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":"info","ts":"2026-05-08T19:05:53.667912Z","caller":"traceutil/trace.go:171","msg":"trace[247975815] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24625; }","duration":"119.907283ms","start":"2026-05-08T19:05:53.547988Z","end":"2026-05-08T19:05:53.667896Z","steps":["trace[247975815] 'agreement among raft nodes before linearized reading' (duration: 119.79243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.667883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:52.120067Z","time spent":"1.547810872s","remote":"127.0.0.1:39096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T19:05:53.949474Z","caller":"traceutil/trace.go:171","msg":"trace[1529873833] linearizableReadLoop","detail":"{readStateIndex:25417; appliedIndex:25414; }","duration":"275.091791ms","start":"2026-05-08T19:05:53.674358Z","end":"2026-05-08T19:05:53.94945Z","steps":["trace[1529873833] 'read index received' (duration: 39.839735ms)","trace[1529873833] 'applied index is now lower than readState.Index' (duration: 235.251126ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:53.949819Z","caller":"traceutil/trace.go:171","msg":"trace[1718507131] transaction","detail":"{read_only:false; response_revision:24628; number_of_response:1; }","duration":"276.973695ms","start":"2026-05-08T19:05:53.672801Z","end":"2026-05-08T19:05:53.949775Z","steps":["trace[1718507131] 'process raft request' (duration: 276.488423ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:53.949811Z","caller":"traceutil/trace.go:171","msg":"trace[2067269085] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"271.088234ms","start":"2026-05-08T19:05:53.678689Z","end":"2026-05-08T19:05:53.949777Z","steps":["trace[2067269085] 'process raft request' (duration: 270.710374ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:53.949834Z","caller":"traceutil/trace.go:171","msg":"trace[1090741258] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"275.006849ms","start":"2026-05-08T19:05:53.674807Z","end":"2026-05-08T19:05:53.949814Z","steps":["trace[1090741258] 'process raft request' (duration: 274.547347ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:53.949944Z","caller":"traceutil/trace.go:171","msg":"trace[252043170] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"277.434786ms","start":"2026-05-08T19:05:53.672498Z","end":"2026-05-08T19:05:53.949933Z","steps":["trace[252043170] 'process raft request' (duration: 239.954597ms)","trace[252043170] 'compare' (duration: 36.685409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:53.949983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.477005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"warn","ts":"2026-05-08T19:05:53.950017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.655885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:53.950034Z","caller":"traceutil/trace.go:171","msg":"trace[673958133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24630; }","duration":"275.710105ms","start":"2026-05-08T19:05:53.674319Z","end":"2026-05-08T19:05:53.950029Z","steps":["trace[673958133] 'agreement among raft nodes before linearized reading' (duration: 275.674184ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:53.950045Z","caller":"traceutil/trace.go:171","msg":"trace[1017132624] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24630; }","duration":"274.548747ms","start":"2026-05-08T19:05:53.67548Z","end":"2026-05-08T19:05:53.950029Z","steps":["trace[1017132624] 'agreement among raft nodes before linearized reading' (duration: 274.325622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.950067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.035592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:53.950113Z","caller":"traceutil/trace.go:171","msg":"trace[427326204] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24630; }","duration":"109.115944ms","start":"2026-05-08T19:05:53.840987Z","end":"2026-05-08T19:05:53.950103Z","steps":["trace[427326204] 'agreement among raft nodes before linearized reading' (duration: 109.040522ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:54.301017Z","caller":"traceutil/trace.go:171","msg":"trace[983191296] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"197.142813ms","start":"2026-05-08T19:05:54.103293Z","end":"2026-05-08T19:05:54.300436Z","steps":["trace[983191296] 'process raft request' (duration: 160.888575ms)","trace[983191296] 'compare' (duration: 35.982452ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:54.310495Z","caller":"traceutil/trace.go:171","msg":"trace[1131119372] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"206.785733ms","start":"2026-05-08T19:05:54.103673Z","end":"2026-05-08T19:05:54.310459Z","steps":["trace[1131119372] 'process raft request' (duration: 206.343882ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:54.310763Z","caller":"traceutil/trace.go:171","msg":"trace[228622985] transaction","detail":"{read_only:false; response_revision:24633; number_of_response:1; }","duration":"204.911659ms","start":"2026-05-08T19:05:54.105827Z","end":"2026-05-08T19:05:54.310739Z","steps":["trace[228622985] 'process raft request' (duration: 204.54924ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:54.311063Z","caller":"traceutil/trace.go:171","msg":"trace[2110274751] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"205.182916ms","start":"2026-05-08T19:05:54.105866Z","end":"2026-05-08T19:05:54.311049Z","steps":["trace[2110274751] 'process raft request' (duration: 204.802566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:54.698208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.101653ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-08T19:05:54.698693Z","caller":"traceutil/trace.go:171","msg":"trace[217886402] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"150.330282ms","start":"2026-05-08T19:05:54.54833Z","end":"2026-05-08T19:05:54.69866Z","steps":["trace[217886402] 'process raft request' (duration: 149.982633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:54.824508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.084655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:54.824636Z","caller":"traceutil/trace.go:171","msg":"trace[150807219] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"206.309193ms","start":"2026-05-08T19:05:54.618298Z","end":"2026-05-08T19:05:54.824607Z","steps":["trace[150807219] 'process raft request' (duration: 89.026213ms)","trace[150807219] 'compare' (duration: 116.841229ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:54.824729Z","caller":"traceutil/trace.go:171","msg":"trace[1473092109] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"132.208867ms","start":"2026-05-08T19:05:54.692501Z","end":"2026-05-08T19:05:54.82471Z","steps":["trace[1473092109] 'process raft request' (duration: 132.114485ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:54.824787Z","caller":"traceutil/trace.go:171","msg":"trace[2062662163] linearizableReadLoop","detail":"{readStateIndex:25426; appliedIndex:25425; }","duration":"147.914704ms","start":"2026-05-08T19:05:54.676835Z","end":"2026-05-08T19:05:54.824749Z","steps":["trace[2062662163] 'read index received' (duration: 30.468691ms)","trace[2062662163] 'applied index is now lower than readState.Index' (duration: 117.443933ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:54.825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.1598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-08T19:05:54.825032Z","caller":"traceutil/trace.go:171","msg":"trace[1910411066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24637; }","duration":"148.249312ms","start":"2026-05-08T19:05:54.676772Z","end":"2026-05-08T19:05:54.825021Z","steps":["trace[1910411066] 'agreement among raft nodes before linearized reading' (duration: 148.071197ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:56.07411Z","caller":"traceutil/trace.go:171","msg":"trace[1028530347] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"117.838553ms","start":"2026-05-08T19:05:55.956243Z","end":"2026-05-08T19:05:56.074081Z","steps":["trace[1028530347] 'process raft request' (duration: 117.649658ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:56.238541Z","caller":"traceutil/trace.go:171","msg":"trace[1310323076] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"280.611023ms","start":"2026-05-08T19:05:55.957906Z","end":"2026-05-08T19:05:56.238517Z","steps":["trace[1310323076] 'process raft request' (duration: 280.570152ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:56.238898Z","caller":"traceutil/trace.go:171","msg":"trace[1821689646] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"281.555145ms","start":"2026-05-08T19:05:55.957332Z","end":"2026-05-08T19:05:56.238887Z","steps":["trace[1821689646] 'process raft request' (duration: 280.964371ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:56.23903Z","caller":"traceutil/trace.go:171","msg":"trace[12598599] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"281.579706ms","start":"2026-05-08T19:05:55.95744Z","end":"2026-05-08T19:05:56.239019Z","steps":["trace[12598599] 'process raft request' (duration: 280.993972ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:56.239524Z","caller":"traceutil/trace.go:171","msg":"trace[1093845151] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"148.59669ms","start":"2026-05-08T19:05:56.090869Z","end":"2026-05-08T19:05:56.239466Z","steps":["trace[1093845151] 'process raft request' (duration: 148.425276ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:07:04.250936Z","caller":"traceutil/trace.go:171","msg":"trace[22525012] linearizableReadLoop","detail":"{readStateIndex:26152; appliedIndex:26151; }","duration":"122.782732ms","start":"2026-05-08T19:07:04.12813Z","end":"2026-05-08T19:07:04.250913Z","steps":["trace[22525012] 'read index received' (duration: 122.553936ms)","trace[22525012] 'applied index is now lower than readState.Index' (duration: 227.986µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:07:04.251065Z","caller":"traceutil/trace.go:171","msg":"trace[54140456] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"139.738868ms","start":"2026-05-08T19:07:04.111315Z","end":"2026-05-08T19:07:04.251053Z","steps":["trace[54140456] 'process raft request' (duration: 139.375499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:07:04.251244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.68729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:07:04.251305Z","caller":"traceutil/trace.go:171","msg":"trace[1907767366] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:25346; }","duration":"122.770732ms","start":"2026-05-08T19:07:04.128519Z","end":"2026-05-08T19:07:04.25129Z","steps":["trace[1907767366] 'agreement among raft nodes before linearized reading' (duration: 122.660239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:07:04.251482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.836293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:1 size:4666"} {"level":"info","ts":"2026-05-08T19:07:04.251523Z","caller":"traceutil/trace.go:171","msg":"trace[280614682] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:1; response_revision:25346; }","duration":"122.894584ms","start":"2026-05-08T19:07:04.128617Z","end":"2026-05-08T19:07:04.251512Z","steps":["trace[280614682] 'agreement among raft nodes before linearized reading' (duration: 122.763291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:07:04.251577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.413106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:07:04.251676Z","caller":"traceutil/trace.go:171","msg":"trace[1297418398] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:25346; }","duration":"123.57024ms","start":"2026-05-08T19:07:04.128086Z","end":"2026-05-08T19:07:04.251657Z","steps":["trace[1297418398] 'agreement among raft nodes before linearized reading' (duration: 123.361945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:07:04.251587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.878099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:07:04.251818Z","caller":"traceutil/trace.go:171","msg":"trace[267075223] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25346; }","duration":"122.192087ms","start":"2026-05-08T19:07:04.129603Z","end":"2026-05-08T19:07:04.251795Z","steps":["trace[267075223] 'agreement among raft nodes before linearized reading' (duration: 121.772957ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:07:38.837708Z","caller":"traceutil/trace.go:171","msg":"trace[1141555899] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"101.698637ms","start":"2026-05-08T19:07:38.735983Z","end":"2026-05-08T19:07:38.837681Z","steps":["trace[1141555899] 'process raft request' (duration: 57.928803ms)","trace[1141555899] 'compare' (duration: 43.310233ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:08:13.338639Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068b3.wal"} {"level":"info","ts":"2026-05-08T19:09:26.024423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23689} {"level":"info","ts":"2026-05-08T19:09:26.156738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23689,"took":"128.247692ms","hash":3328496602,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":38903808,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-05-08T19:09:26.156813Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3328496602,"revision":23689,"compact-revision":20399} {"level":"info","ts":"2026-05-08T19:11:36.390425Z","caller":"traceutil/trace.go:171","msg":"trace[801737493] transaction","detail":"{read_only:false; response_revision:27818; number_of_response:1; }","duration":"107.749293ms","start":"2026-05-08T19:11:36.282645Z","end":"2026-05-08T19:11:36.390394Z","steps":["trace[801737493] 'process raft request' (duration: 107.517668ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:14:26.031221Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26805} {"level":"info","ts":"2026-05-08T19:14:26.170593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26805,"took":"135.128026ms","hash":1900004477,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31666176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T19:14:26.17066Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1900004477,"revision":26805,"compact-revision":23689} {"level":"info","ts":"2026-05-08T19:14:32.035941Z","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-05-08T19:14:32.04131Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-08T19:14:32.041402Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-08T19:19:26.043061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29048} {"level":"info","ts":"2026-05-08T19:19:26.176291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29048,"took":"128.1438ms","hash":1078245317,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":30081024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-08T19:19:26.176361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1078245317,"revision":29048,"compact-revision":26805} {"level":"info","ts":"2026-05-08T19:24:26.048004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31277} {"level":"info","ts":"2026-05-08T19:24:26.176073Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31277,"took":"123.657871ms","hash":3894493886,"current-db-size-bytes":58490880,"current-db-size":"58 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T19:24:26.176161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3894493886,"revision":31277,"compact-revision":29048} {"level":"info","ts":"2026-05-08T19:29:26.054894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33599} {"level":"info","ts":"2026-05-08T19:29:26.21592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33599,"took":"155.865463ms","hash":1721097514,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":32722944,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-08T19:29:26.216002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1721097514,"revision":33599,"compact-revision":31277} {"level":"info","ts":"2026-05-08T19:34:26.062353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35832} {"level":"info","ts":"2026-05-08T19:34:26.199738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35832,"took":"133.941629ms","hash":1798340908,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":31105024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-08T19:34:26.199792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1798340908,"revision":35832,"compact-revision":33599} {"level":"info","ts":"2026-05-08T19:35:56.622568Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d7dbc9f5893cc1f6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-08T19:35:56.627029Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-08T19:35:56.627167Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}