{"level":"warn","ts":"2026-05-28T21:45:33.040299Z","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-28T21:45:33.040368Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-28T21:45:33.040429Z","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-28T21:45:33.040437Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-28T21:45:33.040452Z","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-28T21:45:33.041038Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-05-28T21:45:33.041115Z","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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-28T21:45:33.045612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.29654ms"} {"level":"info","ts":"2026-05-28T21:45:33.051159Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-05-28T21:45:33.051232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T21:45:33.051253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-05-28T21:45:33.051271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T21:45:33.051282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-05-28T21:45:33.051323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-05-28T21:45:33.057056Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T21:45:33.058948Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T21:45:33.060743Z","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-28T21:45:33.063534Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T21:45:33.063787Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T21:45:33.065061Z","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-28T21:45:33.065862Z","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-28T21:45:33.065884Z","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-28T21:45:33.065824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-05-28T21:45:33.066123Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-05-28T21:45:33.069145Z","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-28T21:45:33.069219Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-28T21:45:33.069316Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-05-28T21:45:33.070145Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T21:45:33.0702Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T21:45:33.452025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T21:45:33.452078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T21:45:33.452131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-05-28T21:45:33.452146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-05-28T21:45:33.452153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-28T21:45:33.452165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-05-28T21:45:33.452184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-05-28T21:45:33.453007Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:33.453718Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T21:45:33.453747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:33.454052Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T21:45:33.454481Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:33.454545Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:33.454565Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T21:45:33.456471Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-28T21:45:33.45693Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T21:45:33.456966Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T21:45:33.464421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"warn","ts":"2026-05-28T21:45:53.378303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.603385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T21:45:53.378462Z","caller":"traceutil/trace.go:171","msg":"trace[1295250631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:381; }","duration":"268.807909ms","start":"2026-05-28T21:45:53.109628Z","end":"2026-05-28T21:45:53.378436Z","steps":["trace[1295250631] 'range keys from in-memory index tree' (duration: 268.502503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:45:53.378318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.71513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-28T21:45:53.378691Z","caller":"traceutil/trace.go:171","msg":"trace[816199060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:381; }","duration":"217.994614ms","start":"2026-05-28T21:45:53.160534Z","end":"2026-05-28T21:45:53.378529Z","steps":["trace[816199060] 'range keys from in-memory index tree' (duration: 217.489015ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:46:11.87194Z","caller":"traceutil/trace.go:171","msg":"trace[1307688101] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"114.158953ms","start":"2026-05-28T21:46:11.757752Z","end":"2026-05-28T21:46:11.871911Z","steps":["trace[1307688101] 'process raft request' (duration: 99.298216ms)","trace[1307688101] 'compare' (duration: 14.647083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:47:48.297484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.444581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-28T21:47:48.297517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.103347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b97cf379-0ea1-432a-9bbb-8c2e7ada6ae3\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-28T21:47:48.297606Z","caller":"traceutil/trace.go:171","msg":"trace[1677495306] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b97cf379-0ea1-432a-9bbb-8c2e7ada6ae3; range_end:; response_count:1; response_revision:1827; }","duration":"198.28339ms","start":"2026-05-28T21:47:48.099302Z","end":"2026-05-28T21:47:48.297585Z","steps":["trace[1677495306] 'range keys from in-memory index tree' (duration: 197.876193ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:48.297652Z","caller":"traceutil/trace.go:171","msg":"trace[2089727818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1827; }","duration":"209.597244ms","start":"2026-05-28T21:47:48.087975Z","end":"2026-05-28T21:47:48.297572Z","steps":["trace[2089727818] 'range keys from in-memory index tree' (duration: 209.244976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:48.297545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.563814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T21:47:48.297763Z","caller":"traceutil/trace.go:171","msg":"trace[459943899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1827; }","duration":"191.821549ms","start":"2026-05-28T21:47:48.105923Z","end":"2026-05-28T21:47:48.297744Z","steps":["trace[459943899] 'range keys from in-memory index tree' (duration: 191.402652ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:47:48.420141Z","caller":"traceutil/trace.go:171","msg":"trace[900765118] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"118.192087ms","start":"2026-05-28T21:47:48.301925Z","end":"2026-05-28T21:47:48.420117Z","steps":["trace[900765118] 'process raft request' (duration: 114.468718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:48.723853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.408036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:47:48.723917Z","caller":"traceutil/trace.go:171","msg":"trace[420065327] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1829; }","duration":"195.518248ms","start":"2026-05-28T21:47:48.528384Z","end":"2026-05-28T21:47:48.723903Z","steps":["trace[420065327] 'count revisions from in-memory index tree' (duration: 195.354185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:47:48.724437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.142566ms","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-28T21:47:48.724492Z","caller":"traceutil/trace.go:171","msg":"trace[116838557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1829; }","duration":"144.608974ms","start":"2026-05-28T21:47:48.57987Z","end":"2026-05-28T21:47:48.724479Z","steps":["trace[116838557] 'range keys from in-memory index tree' (duration: 144.021752ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:00.594442Z","caller":"traceutil/trace.go:171","msg":"trace[1019166777] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"120.149744ms","start":"2026-05-28T21:48:00.474254Z","end":"2026-05-28T21:48:00.594404Z","steps":["trace[1019166777] 'process raft request' (duration: 119.94351ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:48:04.064651Z","caller":"traceutil/trace.go:171","msg":"trace[1328717925] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"130.139262ms","start":"2026-05-28T21:48:03.934477Z","end":"2026-05-28T21:48:04.064616Z","steps":["trace[1328717925] 'process raft request' (duration: 129.99515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:48:43.236412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.303784ms","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-28T21:48:43.236499Z","caller":"traceutil/trace.go:171","msg":"trace[648079107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2170; }","duration":"228.431317ms","start":"2026-05-28T21:48:43.008048Z","end":"2026-05-28T21:48:43.236479Z","steps":["trace[648079107] 'range keys from in-memory index tree' (duration: 228.140241ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:49:18.42853Z","caller":"traceutil/trace.go:171","msg":"trace[2136748049] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"105.356581ms","start":"2026-05-28T21:49:18.323153Z","end":"2026-05-28T21:49:18.42851Z","steps":["trace[2136748049] 'process raft request' (duration: 105.237679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:51.946661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.263534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T21:49:51.946992Z","caller":"traceutil/trace.go:171","msg":"trace[8404809] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"233.575211ms","start":"2026-05-28T21:49:51.71336Z","end":"2026-05-28T21:49:51.946935Z","steps":["trace[8404809] 'process raft request' (duration: 101.503792ms)","trace[8404809] 'compare' (duration: 131.140912ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:49:51.947436Z","caller":"traceutil/trace.go:171","msg":"trace[701457247] transaction","detail":"{read_only:false; response_revision:2740; number_of_response:1; }","duration":"126.505189ms","start":"2026-05-28T21:49:51.820916Z","end":"2026-05-28T21:49:51.947421Z","steps":["trace[701457247] 'process raft request' (duration: 125.947979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:49:52.061991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.824596ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-28T21:52:17.393752Z","caller":"traceutil/trace.go:171","msg":"trace[566583831] linearizableReadLoop","detail":"{readStateIndex:3846; appliedIndex:3845; }","duration":"104.386504ms","start":"2026-05-28T21:52:17.289342Z","end":"2026-05-28T21:52:17.393728Z","steps":["trace[566583831] 'read index received' (duration: 104.216801ms)","trace[566583831] 'applied index is now lower than readState.Index' (duration: 168.643µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:52:17.393799Z","caller":"traceutil/trace.go:171","msg":"trace[1971731480] transaction","detail":"{read_only:false; response_revision:3710; number_of_response:1; }","duration":"263.775129ms","start":"2026-05-28T21:52:17.129999Z","end":"2026-05-28T21:52:17.393774Z","steps":["trace[1971731480] 'process raft request' (duration: 263.563325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:52:17.39395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.582087ms","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-28T21:52:17.39401Z","caller":"traceutil/trace.go:171","msg":"trace[537415642] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3710; }","duration":"104.70794ms","start":"2026-05-28T21:52:17.289285Z","end":"2026-05-28T21:52:17.393993Z","steps":["trace[537415642] 'agreement among raft nodes before linearized reading' (duration: 104.588208ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.903946Z","caller":"traceutil/trace.go:171","msg":"trace[1618765136] linearizableReadLoop","detail":"{readStateIndex:4909; appliedIndex:4906; }","duration":"129.139678ms","start":"2026-05-28T21:53:36.774768Z","end":"2026-05-28T21:53:36.903908Z","steps":["trace[1618765136] 'read index received' (duration: 36.704377ms)","trace[1618765136] 'applied index is now lower than readState.Index' (duration: 92.434021ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:36.903995Z","caller":"traceutil/trace.go:171","msg":"trace[583265461] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4730; }","duration":"140.821606ms","start":"2026-05-28T21:53:36.763128Z","end":"2026-05-28T21:53:36.90395Z","steps":["trace[583265461] 'process raft request' (duration: 140.695124ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.904067Z","caller":"traceutil/trace.go:171","msg":"trace[2114584539] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"140.578911ms","start":"2026-05-28T21:53:36.763478Z","end":"2026-05-28T21:53:36.904057Z","steps":["trace[2114584539] 'process raft request' (duration: 140.383628ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.903962Z","caller":"traceutil/trace.go:171","msg":"trace[788432214] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"140.885937ms","start":"2026-05-28T21:53:36.763052Z","end":"2026-05-28T21:53:36.903938Z","steps":["trace[788432214] 'process raft request' (duration: 48.401506ms)","trace[788432214] 'compare' (duration: 92.232637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:53:36.904182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.393682ms","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-28T21:53:36.904282Z","caller":"traceutil/trace.go:171","msg":"trace[161622522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4731; }","duration":"129.521714ms","start":"2026-05-28T21:53:36.774746Z","end":"2026-05-28T21:53:36.904268Z","steps":["trace[161622522] 'agreement among raft nodes before linearized reading' (duration: 129.317111ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:36.91695Z","caller":"traceutil/trace.go:171","msg":"trace[1499022085] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"127.830254ms","start":"2026-05-28T21:53:36.789096Z","end":"2026-05-28T21:53:36.916926Z","steps":["trace[1499022085] 'process raft request' (duration: 127.61646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:36.91701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.088881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-28T21:53:36.917081Z","caller":"traceutil/trace.go:171","msg":"trace[669367598] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4732; }","duration":"142.187433ms","start":"2026-05-28T21:53:36.774877Z","end":"2026-05-28T21:53:36.917065Z","steps":["trace[669367598] 'agreement among raft nodes before linearized reading' (duration: 141.979099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:36.917148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.706162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-h6d7h-tls\" ","response":"range_response_count:1 size:2032"} {"level":"info","ts":"2026-05-28T21:53:36.917203Z","caller":"traceutil/trace.go:171","msg":"trace[72619499] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-h6d7h-tls; range_end:; response_count:1; response_revision:4732; }","duration":"135.834684ms","start":"2026-05-28T21:53:36.781353Z","end":"2026-05-28T21:53:36.917188Z","steps":["trace[72619499] 'agreement among raft nodes before linearized reading' (duration: 135.581019ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:37.058932Z","caller":"traceutil/trace.go:171","msg":"trace[16935129] linearizableReadLoop","detail":"{readStateIndex:4914; appliedIndex:4913; }","duration":"103.768393ms","start":"2026-05-28T21:53:36.955141Z","end":"2026-05-28T21:53:37.05891Z","steps":["trace[16935129] 'read index received' (duration: 103.513388ms)","trace[16935129] 'applied index is now lower than readState.Index' (duration: 253.925µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:37.059038Z","caller":"traceutil/trace.go:171","msg":"trace[836515975] transaction","detail":"{read_only:false; response_revision:4736; number_of_response:1; }","duration":"104.14157ms","start":"2026-05-28T21:53:36.95484Z","end":"2026-05-28T21:53:37.058982Z","steps":["trace[836515975] 'process raft request' (duration: 103.881725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:37.059123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.967246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-28T21:53:37.059178Z","caller":"traceutil/trace.go:171","msg":"trace[291749933] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4736; }","duration":"104.059128ms","start":"2026-05-28T21:53:36.95509Z","end":"2026-05-28T21:53:37.059149Z","steps":["trace[291749933] 'agreement among raft nodes before linearized reading' (duration: 103.924366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:37.059453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.51412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"warn","ts":"2026-05-28T21:53:37.05949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.693541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75502"} {"level":"info","ts":"2026-05-28T21:53:37.059577Z","caller":"traceutil/trace.go:171","msg":"trace[968593584] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4736; }","duration":"103.841955ms","start":"2026-05-28T21:53:36.955717Z","end":"2026-05-28T21:53:37.059559Z","steps":["trace[968593584] 'agreement among raft nodes before linearized reading' (duration: 103.423786ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:37.059517Z","caller":"traceutil/trace.go:171","msg":"trace[1655525721] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4736; }","duration":"102.606411ms","start":"2026-05-28T21:53:36.956893Z","end":"2026-05-28T21:53:37.0595Z","steps":["trace[1655525721] 'agreement among raft nodes before linearized reading' (duration: 102.308105ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:43.35345Z","caller":"traceutil/trace.go:171","msg":"trace[2002374296] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"131.012042ms","start":"2026-05-28T21:53:43.222382Z","end":"2026-05-28T21:53:43.353394Z","steps":["trace[2002374296] 'process raft request' (duration: 130.738717ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:43.366033Z","caller":"traceutil/trace.go:171","msg":"trace[1479510154] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5097; }","duration":"127.350125ms","start":"2026-05-28T21:53:43.238667Z","end":"2026-05-28T21:53:43.366018Z","steps":["trace[1479510154] 'read index received' (duration: 114.201709ms)","trace[1479510154] 'applied index is now lower than readState.Index' (duration: 13.147476ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:43.366218Z","caller":"traceutil/trace.go:171","msg":"trace[1451165819] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"143.468926ms","start":"2026-05-28T21:53:43.222739Z","end":"2026-05-28T21:53:43.366208Z","steps":["trace[1451165819] 'process raft request' (duration: 143.193381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:43.366399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.716792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83776e9c-5a5f-43a9-be1c-69d5e518cc2e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-28T21:53:43.36642Z","caller":"traceutil/trace.go:171","msg":"trace[1466184536] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83776e9c-5a5f-43a9-be1c-69d5e518cc2e; range_end:; response_count:1; response_revision:4917; }","duration":"127.773952ms","start":"2026-05-28T21:53:43.238638Z","end":"2026-05-28T21:53:43.366412Z","steps":["trace[1466184536] 'agreement among raft nodes before linearized reading' (duration: 127.680591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:43.367174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.692028ms","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-28T21:53:43.367259Z","caller":"traceutil/trace.go:171","msg":"trace[1135461949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4918; }","duration":"122.83263ms","start":"2026-05-28T21:53:43.244406Z","end":"2026-05-28T21:53:43.367238Z","steps":["trace[1135461949] 'agreement among raft nodes before linearized reading' (duration: 122.577815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:43.367683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.502963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dn5zh\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-05-28T21:53:43.367722Z","caller":"traceutil/trace.go:171","msg":"trace[659457630] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dn5zh; range_end:; response_count:1; response_revision:4918; }","duration":"114.541205ms","start":"2026-05-28T21:53:43.25316Z","end":"2026-05-28T21:53:43.367701Z","steps":["trace[659457630] 'agreement among raft nodes before linearized reading' (duration: 114.469064ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:43.554755Z","caller":"traceutil/trace.go:171","msg":"trace[1264412346] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"181.907836ms","start":"2026-05-28T21:53:43.372821Z","end":"2026-05-28T21:53:43.554729Z","steps":["trace[1264412346] 'read index received' (duration: 124.078744ms)","trace[1264412346] 'applied index is now lower than readState.Index' (duration: 57.827532ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:53:43.555048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.207042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62900"} {"level":"info","ts":"2026-05-28T21:53:43.555082Z","caller":"traceutil/trace.go:171","msg":"trace[735986392] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4919; }","duration":"182.286364ms","start":"2026-05-28T21:53:43.372785Z","end":"2026-05-28T21:53:43.555071Z","steps":["trace[735986392] 'agreement among raft nodes before linearized reading' (duration: 182.07334ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:43.554854Z","caller":"traceutil/trace.go:171","msg":"trace[2047267907] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"182.976936ms","start":"2026-05-28T21:53:43.371828Z","end":"2026-05-28T21:53:43.554805Z","steps":["trace[2047267907] 'process raft request' (duration: 125.01527ms)","trace[2047267907] 'compare' (duration: 57.765422ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:53:43.563206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.71445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786837"} {"level":"info","ts":"2026-05-28T21:53:43.563402Z","caller":"traceutil/trace.go:171","msg":"trace[980742269] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4920; }","duration":"174.858653ms","start":"2026-05-28T21:53:43.388419Z","end":"2026-05-28T21:53:43.563277Z","steps":["trace[980742269] 'agreement among raft nodes before linearized reading' (duration: 171.45229ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:43.855924Z","caller":"traceutil/trace.go:171","msg":"trace[276341487] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"151.798304ms","start":"2026-05-28T21:53:43.704092Z","end":"2026-05-28T21:53:43.85589Z","steps":["trace[276341487] 'process raft request' (duration: 151.557969ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:43.944559Z","caller":"traceutil/trace.go:171","msg":"trace[582845819] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5103; }","duration":"198.980036ms","start":"2026-05-28T21:53:43.745552Z","end":"2026-05-28T21:53:43.944532Z","steps":["trace[582845819] 'read index received' (duration: 110.339427ms)","trace[582845819] 'applied index is now lower than readState.Index' (duration: 88.639139ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:43.944702Z","caller":"traceutil/trace.go:171","msg":"trace[1044954349] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"239.917822ms","start":"2026-05-28T21:53:43.704697Z","end":"2026-05-28T21:53:43.944614Z","steps":["trace[1044954349] 'process raft request' (duration: 214.14786ms)","trace[1044954349] 'compare' (duration: 25.528608ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T21:53:43.944958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.367903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-28T21:53:43.945079Z","caller":"traceutil/trace.go:171","msg":"trace[41559972] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4922; }","duration":"199.544616ms","start":"2026-05-28T21:53:43.745501Z","end":"2026-05-28T21:53:43.945046Z","steps":["trace[41559972] 'agreement among raft nodes before linearized reading' (duration: 199.166369ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:55.014149Z","caller":"traceutil/trace.go:171","msg":"trace[1837587774] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"135.473372ms","start":"2026-05-28T21:53:54.878645Z","end":"2026-05-28T21:53:55.014119Z","steps":["trace[1837587774] 'process raft request' (duration: 88.756285ms)","trace[1837587774] 'compare' (duration: 46.390681ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:55.015079Z","caller":"traceutil/trace.go:171","msg":"trace[969098271] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"133.621787ms","start":"2026-05-28T21:53:54.881424Z","end":"2026-05-28T21:53:55.015046Z","steps":["trace[969098271] 'process raft request' (duration: 133.146658ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:53:55.015154Z","caller":"traceutil/trace.go:171","msg":"trace[724607907] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"129.070871ms","start":"2026-05-28T21:53:54.886044Z","end":"2026-05-28T21:53:55.015114Z","steps":["trace[724607907] 'process raft request' (duration: 128.710705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:55.583704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.940622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-28T21:53:55.583842Z","caller":"traceutil/trace.go:171","msg":"trace[120369046] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5156; }","duration":"312.088504ms","start":"2026-05-28T21:53:55.271709Z","end":"2026-05-28T21:53:55.583797Z","steps":["trace[120369046] 'range keys from in-memory index tree' (duration: 311.715137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:55.583912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T21:53:55.271695Z","time spent":"312.182115ms","remote":"127.0.0.1:58554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5674,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-28T21:53:55.639951Z","caller":"traceutil/trace.go:171","msg":"trace[1890648034] linearizableReadLoop","detail":"{readStateIndex:5345; appliedIndex:5344; }","duration":"228.773241ms","start":"2026-05-28T21:53:55.411154Z","end":"2026-05-28T21:53:55.639927Z","steps":["trace[1890648034] 'read index received' (duration: 228.612128ms)","trace[1890648034] 'applied index is now lower than readState.Index' (duration: 159.733µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T21:53:55.640042Z","caller":"traceutil/trace.go:171","msg":"trace[1530074134] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"232.746945ms","start":"2026-05-28T21:53:55.407272Z","end":"2026-05-28T21:53:55.640019Z","steps":["trace[1530074134] 'process raft request' (duration: 232.503081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:55.640235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.081276ms","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-28T21:53:55.640288Z","caller":"traceutil/trace.go:171","msg":"trace[1462147066] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5157; }","duration":"229.189208ms","start":"2026-05-28T21:53:55.411084Z","end":"2026-05-28T21:53:55.640273Z","steps":["trace[1462147066] 'agreement among raft nodes before linearized reading' (duration: 228.985454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:55.640341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.578638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-28T21:53:55.640443Z","caller":"traceutil/trace.go:171","msg":"trace[1010078276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5157; }","duration":"228.71858ms","start":"2026-05-28T21:53:55.411703Z","end":"2026-05-28T21:53:55.640422Z","steps":["trace[1010078276] 'agreement among raft nodes before linearized reading' (duration: 228.421474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T21:53:55.640425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.608084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T21:53:55.640513Z","caller":"traceutil/trace.go:171","msg":"trace[13639001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5157; }","duration":"207.731316ms","start":"2026-05-28T21:53:55.432762Z","end":"2026-05-28T21:53:55.640493Z","steps":["trace[13639001] 'agreement among raft nodes before linearized reading' (duration: 207.570603ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T21:55:33.48666Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-05-28T21:55:33.558651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"70.433473ms","hash":4280670715,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":34279424,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T21:55:33.558731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4280670715,"revision":2967,"compact-revision":-1} {"level":"info","ts":"2026-05-28T22:00:14.884017Z","caller":"traceutil/trace.go:171","msg":"trace[1902325090] linearizableReadLoop","detail":"{readStateIndex:8447; appliedIndex:8446; }","duration":"273.000714ms","start":"2026-05-28T22:00:14.611Z","end":"2026-05-28T22:00:14.884001Z","steps":["trace[1902325090] 'read index received' (duration: 272.853991ms)","trace[1902325090] 'applied index is now lower than readState.Index' (duration: 146.213µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:14.884236Z","caller":"traceutil/trace.go:171","msg":"trace[706737327] transaction","detail":"{read_only:false; response_revision:8166; number_of_response:1; }","duration":"436.883324ms","start":"2026-05-28T22:00:14.447346Z","end":"2026-05-28T22:00:14.884229Z","steps":["trace[706737327] 'process raft request' (duration: 436.555818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.884314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:00:14.447328Z","time spent":"436.930055ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:00:14.884449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.455592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T22:00:14.884471Z","caller":"traceutil/trace.go:171","msg":"trace[1683112939] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:8166; }","duration":"273.516533ms","start":"2026-05-28T22:00:14.610949Z","end":"2026-05-28T22:00:14.884466Z","steps":["trace[1683112939] 'agreement among raft nodes before linearized reading' (duration: 273.460722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.88458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.744284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.884601Z","caller":"traceutil/trace.go:171","msg":"trace[909911244] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8166; }","duration":"217.765415ms","start":"2026-05-28T22:00:14.666829Z","end":"2026-05-28T22:00:14.884595Z","steps":["trace[909911244] 'agreement among raft nodes before linearized reading' (duration: 217.732764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.884732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.293476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.884752Z","caller":"traceutil/trace.go:171","msg":"trace[1394272114] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8166; }","duration":"108.327846ms","start":"2026-05-28T22:00:14.77642Z","end":"2026-05-28T22:00:14.884748Z","steps":["trace[1394272114] 'agreement among raft nodes before linearized reading' (duration: 108.295676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.884764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.275706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T22:00:14.884834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.064485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.88484Z","caller":"traceutil/trace.go:171","msg":"trace[551506841] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8166; }","duration":"108.372058ms","start":"2026-05-28T22:00:14.776445Z","end":"2026-05-28T22:00:14.884817Z","steps":["trace[551506841] 'agreement among raft nodes before linearized reading' (duration: 108.241055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.884867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.618417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.884881Z","caller":"traceutil/trace.go:171","msg":"trace[1455594804] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8166; }","duration":"180.651987ms","start":"2026-05-28T22:00:14.704224Z","end":"2026-05-28T22:00:14.884876Z","steps":["trace[1455594804] 'agreement among raft nodes before linearized reading' (duration: 180.633876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.884891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.272581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T22:00:14.884974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.69431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.884989Z","caller":"traceutil/trace.go:171","msg":"trace[1334142704] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8166; }","duration":"194.717712ms","start":"2026-05-28T22:00:14.690266Z","end":"2026-05-28T22:00:14.884984Z","steps":["trace[1334142704] 'agreement among raft nodes before linearized reading' (duration: 194.698631ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:14.884997Z","caller":"traceutil/trace.go:171","msg":"trace[283637525] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8166; }","duration":"162.428524ms","start":"2026-05-28T22:00:14.722547Z","end":"2026-05-28T22:00:14.884976Z","steps":["trace[283637525] 'agreement among raft nodes before linearized reading' (duration: 162.22773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.553236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885085Z","caller":"traceutil/trace.go:171","msg":"trace[207427012] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8166; }","duration":"195.610678ms","start":"2026-05-28T22:00:14.68947Z","end":"2026-05-28T22:00:14.88508Z","steps":["trace[207427012] 'agreement among raft nodes before linearized reading' (duration: 195.585347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.88515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.73681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885162Z","caller":"traceutil/trace.go:171","msg":"trace[235627966] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8166; }","duration":"195.75ms","start":"2026-05-28T22:00:14.689408Z","end":"2026-05-28T22:00:14.885158Z","steps":["trace[235627966] 'agreement among raft nodes before linearized reading' (duration: 195.72961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.151912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885238Z","caller":"traceutil/trace.go:171","msg":"trace[1452032012] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8166; }","duration":"218.181702ms","start":"2026-05-28T22:00:14.667052Z","end":"2026-05-28T22:00:14.885234Z","steps":["trace[1452032012] 'agreement among raft nodes before linearized reading' (duration: 218.159922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.436407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885326Z","caller":"traceutil/trace.go:171","msg":"trace[42344218] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8166; }","duration":"218.488318ms","start":"2026-05-28T22:00:14.666834Z","end":"2026-05-28T22:00:14.885322Z","steps":["trace[42344218] 'agreement among raft nodes before linearized reading' (duration: 218.464498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.373312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885363Z","caller":"traceutil/trace.go:171","msg":"trace[711607677] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8166; }","duration":"146.427073ms","start":"2026-05-28T22:00:14.738923Z","end":"2026-05-28T22:00:14.885351Z","steps":["trace[711607677] 'agreement among raft nodes before linearized reading' (duration: 146.367441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.193358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885425Z","caller":"traceutil/trace.go:171","msg":"trace[86062249] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8166; }","duration":"146.28107ms","start":"2026-05-28T22:00:14.739131Z","end":"2026-05-28T22:00:14.885412Z","steps":["trace[86062249] 'agreement among raft nodes before linearized reading' (duration: 146.153497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.646158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.88555Z","caller":"traceutil/trace.go:171","msg":"trace[658799795] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8166; }","duration":"162.694309ms","start":"2026-05-28T22:00:14.722848Z","end":"2026-05-28T22:00:14.885542Z","steps":["trace[658799795] 'agreement among raft nodes before linearized reading' (duration: 162.647998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.225788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885609Z","caller":"traceutil/trace.go:171","msg":"trace[59587032] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8166; }","duration":"130.255388ms","start":"2026-05-28T22:00:14.755347Z","end":"2026-05-28T22:00:14.885602Z","steps":["trace[59587032] 'agreement among raft nodes before linearized reading' (duration: 130.226008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.507169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T22:00:14.885676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.059455ms","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:5"} {"level":"info","ts":"2026-05-28T22:00:14.885692Z","caller":"traceutil/trace.go:171","msg":"trace[1403804012] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8166; }","duration":"108.54881ms","start":"2026-05-28T22:00:14.777136Z","end":"2026-05-28T22:00:14.885685Z","steps":["trace[1403804012] 'agreement among raft nodes before linearized reading' (duration: 108.505379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.945781ms","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-28T22:00:14.885705Z","caller":"traceutil/trace.go:171","msg":"trace[1709250971] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:8166; }","duration":"163.104617ms","start":"2026-05-28T22:00:14.722592Z","end":"2026-05-28T22:00:14.885697Z","steps":["trace[1709250971] 'agreement among raft nodes before linearized reading' (duration: 163.060576ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:14.885722Z","caller":"traceutil/trace.go:171","msg":"trace[553828418] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8166; }","duration":"130.979332ms","start":"2026-05-28T22:00:14.754738Z","end":"2026-05-28T22:00:14.885717Z","steps":["trace[553828418] 'agreement among raft nodes before linearized reading' (duration: 130.952712ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:14.884849Z","caller":"traceutil/trace.go:171","msg":"trace[1311585245] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8166; }","duration":"180.095086ms","start":"2026-05-28T22:00:14.70475Z","end":"2026-05-28T22:00:14.884845Z","steps":["trace[1311585245] 'agreement among raft nodes before linearized reading' (duration: 180.068726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.885786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.057933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885799Z","caller":"traceutil/trace.go:171","msg":"trace[1710668605] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8166; }","duration":"131.095155ms","start":"2026-05-28T22:00:14.7547Z","end":"2026-05-28T22:00:14.885795Z","steps":["trace[1710668605] 'agreement among raft nodes before linearized reading' (duration: 131.074744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.884955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.899832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885828Z","caller":"traceutil/trace.go:171","msg":"trace[817578944] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8166; }","duration":"181.787218ms","start":"2026-05-28T22:00:14.704032Z","end":"2026-05-28T22:00:14.885819Z","steps":["trace[817578944] 'agreement among raft nodes before linearized reading' (duration: 180.910402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:14.88586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.496434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:14.885874Z","caller":"traceutil/trace.go:171","msg":"trace[1213700764] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8166; }","duration":"146.546075ms","start":"2026-05-28T22:00:14.739323Z","end":"2026-05-28T22:00:14.885869Z","steps":["trace[1213700764] 'agreement among raft nodes before linearized reading' (duration: 146.525425ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:16.394964Z","caller":"traceutil/trace.go:171","msg":"trace[569050109] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"166.835488ms","start":"2026-05-28T22:00:16.228104Z","end":"2026-05-28T22:00:16.394939Z","steps":["trace[569050109] 'process raft request' (duration: 166.649644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:32.519095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.506068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T22:00:32.519413Z","caller":"traceutil/trace.go:171","msg":"trace[2055108677] transaction","detail":"{read_only:false; response_revision:8266; number_of_response:1; }","duration":"254.20943ms","start":"2026-05-28T22:00:32.265158Z","end":"2026-05-28T22:00:32.519368Z","steps":["trace[2055108677] 'process raft request' (duration: 118.302424ms)","trace[2055108677] 'compare' (duration: 135.240073ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:33.493011Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6103} {"level":"info","ts":"2026-05-28T22:00:33.586126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6103,"took":"91.678224ms","hash":3759742643,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":22679552,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-28T22:00:33.586182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3759742643,"revision":6103,"compact-revision":2967} {"level":"info","ts":"2026-05-28T22:00:44.465655Z","caller":"traceutil/trace.go:171","msg":"trace[1789125631] transaction","detail":"{read_only:false; response_revision:8380; number_of_response:1; }","duration":"109.381486ms","start":"2026-05-28T22:00:44.356239Z","end":"2026-05-28T22:00:44.46562Z","steps":["trace[1789125631] 'process raft request' (duration: 109.215623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:57.370215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.31182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T22:00:57.370387Z","caller":"traceutil/trace.go:171","msg":"trace[902056558] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8791; }","duration":"157.870078ms","start":"2026-05-28T22:00:57.212502Z","end":"2026-05-28T22:00:57.370372Z","steps":["trace[902056558] 'read index received' (duration: 22.409321ms)","trace[902056558] 'applied index is now lower than readState.Index' (duration: 135.459877ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:57.370422Z","caller":"traceutil/trace.go:171","msg":"trace[1628027032] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"170.659289ms","start":"2026-05-28T22:00:57.199734Z","end":"2026-05-28T22:00:57.370393Z","steps":["trace[1628027032] 'process raft request' (duration: 56.096215ms)","trace[1628027032] 'compare' (duration: 114.195578ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:00:57.370438Z","caller":"traceutil/trace.go:171","msg":"trace[316082882] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"139.166267ms","start":"2026-05-28T22:00:57.231238Z","end":"2026-05-28T22:00:57.370404Z","steps":["trace[316082882] 'process raft request' (duration: 139.078015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:57.370473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.012761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:00:57.37051Z","caller":"traceutil/trace.go:171","msg":"trace[1436210578] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:8496; }","duration":"158.070831ms","start":"2026-05-28T22:00:57.212427Z","end":"2026-05-28T22:00:57.370498Z","steps":["trace[1436210578] 'agreement among raft nodes before linearized reading' (duration: 158.01364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:00:57.434328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.58951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-wjkwv\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-05-28T22:00:57.434477Z","caller":"traceutil/trace.go:171","msg":"trace[1506684889] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-wjkwv; range_end:; response_count:1; response_revision:8498; }","duration":"118.783154ms","start":"2026-05-28T22:00:57.315678Z","end":"2026-05-28T22:00:57.434461Z","steps":["trace[1506684889] 'agreement among raft nodes before linearized reading' (duration: 118.482028ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:00:57.434285Z","caller":"traceutil/trace.go:171","msg":"trace[1816082565] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"107.336199ms","start":"2026-05-28T22:00:57.326888Z","end":"2026-05-28T22:00:57.434224Z","steps":["trace[1816082565] 'process raft request' (duration: 107.108474ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:01:46.256808Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002410.wal"} {"level":"info","ts":"2026-05-28T22:02:12.98252Z","caller":"traceutil/trace.go:171","msg":"trace[1307310192] transaction","detail":"{read_only:false; response_revision:9244; number_of_response:1; }","duration":"105.368378ms","start":"2026-05-28T22:02:12.877122Z","end":"2026-05-28T22:02:12.982491Z","steps":["trace[1307310192] 'process raft request' (duration: 105.183265ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:02:12.982688Z","caller":"traceutil/trace.go:171","msg":"trace[4522099] transaction","detail":"{read_only:false; response_revision:9245; number_of_response:1; }","duration":"105.465669ms","start":"2026-05-28T22:02:12.87719Z","end":"2026-05-28T22:02:12.982655Z","steps":["trace[4522099] 'process raft request' (duration: 105.259015ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:02:13.094891Z","caller":"traceutil/trace.go:171","msg":"trace[1029024799] transaction","detail":"{read_only:false; response_revision:9246; number_of_response:1; }","duration":"107.737642ms","start":"2026-05-28T22:02:12.987122Z","end":"2026-05-28T22:02:13.09486Z","steps":["trace[1029024799] 'process raft request' (duration: 61.460054ms)","trace[1029024799] 'compare' (duration: 46.154936ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:03:11.818219Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:03:11.823601Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-28T22:03:11.823703Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-28T22:05:33.501765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8272} {"level":"info","ts":"2026-05-28T22:05:33.578738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8272,"took":"74.524699ms","hash":1071920965,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":26619904,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-28T22:05:33.578812Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1071920965,"revision":8272,"compact-revision":6103} {"level":"warn","ts":"2026-05-28T22:05:56.905826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.57383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T22:05:56.905939Z","caller":"traceutil/trace.go:171","msg":"trace[1607031670] linearizableReadLoop","detail":"{readStateIndex:11465; appliedIndex:11464; }","duration":"284.219277ms","start":"2026-05-28T22:05:56.621703Z","end":"2026-05-28T22:05:56.905923Z","steps":["trace[1607031670] 'read index received' (duration: 12.369972ms)","trace[1607031670] 'applied index is now lower than readState.Index' (duration: 271.846445ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:05:56.906078Z","caller":"traceutil/trace.go:171","msg":"trace[1437330109] transaction","detail":"{read_only:false; response_revision:11090; number_of_response:1; }","duration":"331.102297ms","start":"2026-05-28T22:05:56.574963Z","end":"2026-05-28T22:05:56.906065Z","steps":["trace[1437330109] 'process raft request' (duration: 59.15239ms)","trace[1437330109] 'compare' (duration: 271.0376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:05:56.906163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:05:56.574942Z","time spent":"331.190599ms","remote":"127.0.0.1:41840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:05:56.906532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.833749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-695bc7f75b-bxc9s.18b3d9f534a3ab5a\" ","response":"range_response_count:1 size:804"} {"level":"info","ts":"2026-05-28T22:05:56.906569Z","caller":"traceutil/trace.go:171","msg":"trace[1490238665] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-695bc7f75b-bxc9s.18b3d9f534a3ab5a; range_end:; response_count:1; response_revision:11090; }","duration":"284.90879ms","start":"2026-05-28T22:05:56.621648Z","end":"2026-05-28T22:05:56.906557Z","steps":["trace[1490238665] 'agreement among raft nodes before linearized reading' (duration: 284.745167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:05:56.906788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.879489ms","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-28T22:05:56.906821Z","caller":"traceutil/trace.go:171","msg":"trace[1162139028] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11090; }","duration":"227.95812ms","start":"2026-05-28T22:05:56.678854Z","end":"2026-05-28T22:05:56.906812Z","steps":["trace[1162139028] 'agreement among raft nodes before linearized reading' (duration: 227.899929ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:05:57.058721Z","caller":"traceutil/trace.go:171","msg":"trace[73496545] transaction","detail":"{read_only:false; response_revision:11091; number_of_response:1; }","duration":"148.932046ms","start":"2026-05-28T22:05:56.909756Z","end":"2026-05-28T22:05:57.058688Z","steps":["trace[73496545] 'process raft request' (duration: 136.067525ms)","trace[73496545] 'compare' (duration: 12.727639ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:05:57.062632Z","caller":"traceutil/trace.go:171","msg":"trace[673185644] linearizableReadLoop","detail":"{readStateIndex:11467; appliedIndex:11465; }","duration":"145.481351ms","start":"2026-05-28T22:05:56.917123Z","end":"2026-05-28T22:05:57.062604Z","steps":["trace[673185644] 'read index received' (duration: 128.676346ms)","trace[673185644] 'applied index is now lower than readState.Index' (duration: 16.803645ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:05:57.062756Z","caller":"traceutil/trace.go:171","msg":"trace[1148873029] transaction","detail":"{read_only:false; response_revision:11092; number_of_response:1; }","duration":"149.421585ms","start":"2026-05-28T22:05:56.91329Z","end":"2026-05-28T22:05:57.062711Z","steps":["trace[1148873029] 'process raft request' (duration: 149.172661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:05:57.06287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.720266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-28T22:05:57.062913Z","caller":"traceutil/trace.go:171","msg":"trace[616670492] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:11092; }","duration":"145.817438ms","start":"2026-05-28T22:05:56.917084Z","end":"2026-05-28T22:05:57.062901Z","steps":["trace[616670492] 'agreement among raft nodes before linearized reading' (duration: 145.650605ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:10:33.508452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10955} {"level":"info","ts":"2026-05-28T22:10:33.587475Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10955,"took":"76.405645ms","hash":4190811668,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":28360704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-28T22:10:33.58754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4190811668,"revision":10955,"compact-revision":8272} {"level":"info","ts":"2026-05-28T22:12:07.872578Z","caller":"traceutil/trace.go:171","msg":"trace[1326162595] transaction","detail":"{read_only:false; response_revision:13595; number_of_response:1; }","duration":"116.242783ms","start":"2026-05-28T22:12:07.756305Z","end":"2026-05-28T22:12:07.872548Z","steps":["trace[1326162595] 'process raft request' (duration: 116.006929ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:15.643706Z","caller":"traceutil/trace.go:171","msg":"trace[901691048] linearizableReadLoop","detail":"{readStateIndex:14111; appliedIndex:14110; }","duration":"163.935042ms","start":"2026-05-28T22:12:15.479741Z","end":"2026-05-28T22:12:15.643676Z","steps":["trace[901691048] 'read index received' (duration: 163.771989ms)","trace[901691048] 'applied index is now lower than readState.Index' (duration: 161.403µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:12:15.643823Z","caller":"traceutil/trace.go:171","msg":"trace[122241588] transaction","detail":"{read_only:false; response_revision:13647; number_of_response:1; }","duration":"164.977302ms","start":"2026-05-28T22:12:15.4788Z","end":"2026-05-28T22:12:15.643777Z","steps":["trace[122241588] 'process raft request' (duration: 164.668316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.644148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.3901ms","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-28T22:12:15.644212Z","caller":"traceutil/trace.go:171","msg":"trace[129704672] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13647; }","duration":"164.508862ms","start":"2026-05-28T22:12:15.479683Z","end":"2026-05-28T22:12:15.644192Z","steps":["trace[129704672] 'agreement among raft nodes before linearized reading' (duration: 164.113915ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:12:15.864516Z","caller":"traceutil/trace.go:171","msg":"trace[281227278] transaction","detail":"{read_only:false; response_revision:13648; number_of_response:1; }","duration":"384.094697ms","start":"2026-05-28T22:12:15.48039Z","end":"2026-05-28T22:12:15.864485Z","steps":["trace[281227278] 'process raft request' (duration: 365.636458ms)","trace[281227278] 'compare' (duration: 18.251645ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:12:15.864879Z","caller":"traceutil/trace.go:171","msg":"trace[254872651] transaction","detail":"{read_only:false; response_revision:13649; number_of_response:1; }","duration":"215.273843ms","start":"2026-05-28T22:12:15.649566Z","end":"2026-05-28T22:12:15.86484Z","steps":["trace[254872651] 'process raft request' (duration: 214.868514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.864903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:12:15.480374Z","time spent":"384.23451ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:12:15.865144Z","caller":"traceutil/trace.go:171","msg":"trace[1497009428] linearizableReadLoop","detail":"{readStateIndex:14114; appliedIndex:14111; }","duration":"120.307596ms","start":"2026-05-28T22:12:15.744804Z","end":"2026-05-28T22:12:15.865112Z","steps":["trace[1497009428] 'read index received' (duration: 101.240855ms)","trace[1497009428] 'applied index is now lower than readState.Index' (duration: 19.065661ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:12:15.865188Z","caller":"traceutil/trace.go:171","msg":"trace[628219384] transaction","detail":"{read_only:false; response_revision:13650; number_of_response:1; }","duration":"205.192072ms","start":"2026-05-28T22:12:15.659979Z","end":"2026-05-28T22:12:15.865171Z","steps":["trace[628219384] 'process raft request' (duration: 204.717833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:15.865329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.498869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:12:15.865387Z","caller":"traceutil/trace.go:171","msg":"trace[1379854157] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:13650; }","duration":"120.612792ms","start":"2026-05-28T22:12:15.744759Z","end":"2026-05-28T22:12:15.865371Z","steps":["trace[1379854157] 'agreement among raft nodes before linearized reading' (duration: 120.49248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:12:36.893937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.909945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-28T22:12:36.894023Z","caller":"traceutil/trace.go:171","msg":"trace[1989015291] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13955; }","duration":"179.047217ms","start":"2026-05-28T22:12:36.714958Z","end":"2026-05-28T22:12:36.894006Z","steps":["trace[1989015291] 'range keys from in-memory index tree' (duration: 178.780121ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:13:46.182865Z","caller":"traceutil/trace.go:171","msg":"trace[1010016850] transaction","detail":"{read_only:false; response_revision:14676; number_of_response:1; }","duration":"109.377889ms","start":"2026-05-28T22:13:46.073463Z","end":"2026-05-28T22:13:46.182841Z","steps":["trace[1010016850] 'process raft request' (duration: 109.248956ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:13:51.729443Z","caller":"traceutil/trace.go:171","msg":"trace[1221506509] linearizableReadLoop","detail":"{readStateIndex:15202; appliedIndex:15201; }","duration":"137.794996ms","start":"2026-05-28T22:13:51.591625Z","end":"2026-05-28T22:13:51.72942Z","steps":["trace[1221506509] 'read index received' (duration: 137.669674ms)","trace[1221506509] 'applied index is now lower than readState.Index' (duration: 123.822µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:13:51.729715Z","caller":"traceutil/trace.go:171","msg":"trace[1369946945] transaction","detail":"{read_only:false; response_revision:14705; number_of_response:1; }","duration":"173.701517ms","start":"2026-05-28T22:13:51.556002Z","end":"2026-05-28T22:13:51.729703Z","steps":["trace[1369946945] 'process raft request' (duration: 173.285529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:51.729904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.264565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:13:51.729945Z","caller":"traceutil/trace.go:171","msg":"trace[356217824] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14705; }","duration":"138.342118ms","start":"2026-05-28T22:13:51.591591Z","end":"2026-05-28T22:13:51.729934Z","steps":["trace[356217824] 'agreement among raft nodes before linearized reading' (duration: 138.270776ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:13:51.84193Z","caller":"traceutil/trace.go:171","msg":"trace[1910712124] linearizableReadLoop","detail":"{readStateIndex:15203; appliedIndex:15202; }","duration":"112.364475ms","start":"2026-05-28T22:13:51.729543Z","end":"2026-05-28T22:13:51.841908Z","steps":["trace[1910712124] 'read index received' (duration: 39.402525ms)","trace[1910712124] 'applied index is now lower than readState.Index' (duration: 72.96043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:13:51.842038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.758395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T22:13:51.842075Z","caller":"traceutil/trace.go:171","msg":"trace[1898372369] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14705; }","duration":"249.842427ms","start":"2026-05-28T22:13:51.592223Z","end":"2026-05-28T22:13:51.842065Z","steps":["trace[1898372369] 'agreement among raft nodes before linearized reading' (duration: 249.762206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:51.842406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.564243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-28T22:13:51.842492Z","caller":"traceutil/trace.go:171","msg":"trace[1222746805] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14705; }","duration":"248.686145ms","start":"2026-05-28T22:13:51.593787Z","end":"2026-05-28T22:13:51.842473Z","steps":["trace[1222746805] 'agreement among raft nodes before linearized reading' (duration: 248.402259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:51.842534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.217794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-05-28T22:13:51.842639Z","caller":"traceutil/trace.go:171","msg":"trace[1211319017] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14705; }","duration":"250.352636ms","start":"2026-05-28T22:13:51.59226Z","end":"2026-05-28T22:13:51.842612Z","steps":["trace[1211319017] 'agreement among raft nodes before linearized reading' (duration: 250.02893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:13:51.842894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.927069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-28T22:13:51.843946Z","caller":"traceutil/trace.go:171","msg":"trace[2120583313] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14705; }","duration":"249.295226ms","start":"2026-05-28T22:13:51.593684Z","end":"2026-05-28T22:13:51.842979Z","steps":["trace[2120583313] 'agreement among raft nodes before linearized reading' (duration: 248.712265ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:15:33.514706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12996} {"level":"info","ts":"2026-05-28T22:15:33.591868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12996,"took":"75.187953ms","hash":4259839119,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":30904320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T22:15:33.59195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4259839119,"revision":12996,"compact-revision":10955} {"level":"info","ts":"2026-05-28T22:19:33.046401Z","caller":"traceutil/trace.go:171","msg":"trace[1579673778] transaction","detail":"{read_only:false; response_revision:17258; number_of_response:1; }","duration":"130.440816ms","start":"2026-05-28T22:19:32.915932Z","end":"2026-05-28T22:19:33.046372Z","steps":["trace[1579673778] 'process raft request' (duration: 130.257623ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:33.173422Z","caller":"traceutil/trace.go:171","msg":"trace[1541521302] linearizableReadLoop","detail":"{readStateIndex:17839; appliedIndex:17837; }","duration":"208.526856ms","start":"2026-05-28T22:19:32.964865Z","end":"2026-05-28T22:19:33.173392Z","steps":["trace[1541521302] 'read index received' (duration: 81.295481ms)","trace[1541521302] 'applied index is now lower than readState.Index' (duration: 127.230325ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:19:33.173602Z","caller":"traceutil/trace.go:171","msg":"trace[1846566694] transaction","detail":"{read_only:false; response_revision:17259; number_of_response:1; }","duration":"212.139584ms","start":"2026-05-28T22:19:32.961368Z","end":"2026-05-28T22:19:33.173507Z","steps":["trace[1846566694] 'process raft request' (duration: 210.982523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:33.173666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.78263ms","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-28T22:19:33.173708Z","caller":"traceutil/trace.go:171","msg":"trace[567477598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17259; }","duration":"208.871843ms","start":"2026-05-28T22:19:32.964825Z","end":"2026-05-28T22:19:33.173697Z","steps":["trace[567477598] 'agreement among raft nodes before linearized reading' (duration: 208.705319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:34.112375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.019504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-phczz\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-05-28T22:19:34.112456Z","caller":"traceutil/trace.go:171","msg":"trace[2067232606] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-phczz; range_end:; response_count:1; response_revision:17262; }","duration":"159.148197ms","start":"2026-05-28T22:19:33.953292Z","end":"2026-05-28T22:19:34.11244Z","steps":["trace[2067232606] 'range keys from in-memory index tree' (duration: 158.844051ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:19:34.231809Z","caller":"traceutil/trace.go:171","msg":"trace[414443936] linearizableReadLoop","detail":"{readStateIndex:17844; appliedIndex:17843; }","duration":"114.591208ms","start":"2026-05-28T22:19:34.117194Z","end":"2026-05-28T22:19:34.231785Z","steps":["trace[414443936] 'read index received' (duration: 76.675475ms)","trace[414443936] 'applied index is now lower than readState.Index' (duration: 37.914633ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:19:34.231952Z","caller":"traceutil/trace.go:171","msg":"trace[1235710667] transaction","detail":"{read_only:false; response_revision:17264; number_of_response:1; }","duration":"115.869002ms","start":"2026-05-28T22:19:34.116047Z","end":"2026-05-28T22:19:34.231916Z","steps":["trace[1235710667] 'process raft request' (duration: 106.479425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:34.232067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.848223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-28T22:19:34.232108Z","caller":"traceutil/trace.go:171","msg":"trace[171713418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17264; }","duration":"114.933854ms","start":"2026-05-28T22:19:34.117165Z","end":"2026-05-28T22:19:34.232099Z","steps":["trace[171713418] 'agreement among raft nodes before linearized reading' (duration: 114.774191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:34.232256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.861917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-28T22:19:34.232282Z","caller":"traceutil/trace.go:171","msg":"trace[255849700] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17264; }","duration":"110.917408ms","start":"2026-05-28T22:19:34.121355Z","end":"2026-05-28T22:19:34.232272Z","steps":["trace[255849700] 'agreement among raft nodes before linearized reading' (duration: 110.850857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:19:34.232779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.856485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946899"} {"level":"info","ts":"2026-05-28T22:19:34.23285Z","caller":"traceutil/trace.go:171","msg":"trace[1150438140] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17264; }","duration":"112.943488ms","start":"2026-05-28T22:19:34.119892Z","end":"2026-05-28T22:19:34.232835Z","steps":["trace[1150438140] 'agreement among raft nodes before linearized reading' (duration: 112.05879ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:20:33.52454Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15356} {"level":"info","ts":"2026-05-28T22:20:33.616529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15356,"took":"88.64639ms","hash":3166009209,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":33595392,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-28T22:20:33.616587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3166009209,"revision":15356,"compact-revision":12996} {"level":"info","ts":"2026-05-28T22:23:39.181967Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:23:39.187279Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-28T22:23:39.187374Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-28T22:25:14.087472Z","caller":"traceutil/trace.go:171","msg":"trace[1576491577] transaction","detail":"{read_only:false; response_revision:19903; number_of_response:1; }","duration":"121.094943ms","start":"2026-05-28T22:25:13.966351Z","end":"2026-05-28T22:25:14.087446Z","steps":["trace[1576491577] 'process raft request' (duration: 120.763617ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:19.869574Z","caller":"traceutil/trace.go:171","msg":"trace[699550586] transaction","detail":"{read_only:false; response_revision:19934; number_of_response:1; }","duration":"254.791742ms","start":"2026-05-28T22:25:19.614756Z","end":"2026-05-28T22:25:19.869547Z","steps":["trace[699550586] 'process raft request' (duration: 254.624659ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:20.303812Z","caller":"traceutil/trace.go:171","msg":"trace[888129756] linearizableReadLoop","detail":"{readStateIndex:20607; appliedIndex:20606; }","duration":"163.694976ms","start":"2026-05-28T22:25:20.140093Z","end":"2026-05-28T22:25:20.303788Z","steps":["trace[888129756] 'read index received' (duration: 163.485312ms)","trace[888129756] 'applied index is now lower than readState.Index' (duration: 206.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:25:20.303911Z","caller":"traceutil/trace.go:171","msg":"trace[1744692295] transaction","detail":"{read_only:false; response_revision:19936; number_of_response:1; }","duration":"166.363496ms","start":"2026-05-28T22:25:20.137518Z","end":"2026-05-28T22:25:20.303882Z","steps":["trace[1744692295] 'process raft request' (duration: 166.125311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:25:20.304047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.92952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T22:25:20.304101Z","caller":"traceutil/trace.go:171","msg":"trace[1554116874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19936; }","duration":"164.014681ms","start":"2026-05-28T22:25:20.140065Z","end":"2026-05-28T22:25:20.30408Z","steps":["trace[1554116874] 'agreement among raft nodes before linearized reading' (duration: 163.844758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:25:20.30419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.494613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-28T22:25:20.304186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.021231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-28T22:25:20.304271Z","caller":"traceutil/trace.go:171","msg":"trace[963257865] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19936; }","duration":"162.592085ms","start":"2026-05-28T22:25:20.141657Z","end":"2026-05-28T22:25:20.304249Z","steps":["trace[963257865] 'agreement among raft nodes before linearized reading' (duration: 162.393072ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:20.304299Z","caller":"traceutil/trace.go:171","msg":"trace[1180243224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19936; }","duration":"164.180325ms","start":"2026-05-28T22:25:20.140092Z","end":"2026-05-28T22:25:20.304272Z","steps":["trace[1180243224] 'agreement among raft nodes before linearized reading' (duration: 163.883179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:25:20.580337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.745404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:25:20.580805Z","caller":"traceutil/trace.go:171","msg":"trace[1943551864] linearizableReadLoop","detail":"{readStateIndex:20610; appliedIndex:20607; }","duration":"149.354275ms","start":"2026-05-28T22:25:20.431432Z","end":"2026-05-28T22:25:20.580786Z","steps":["trace[1943551864] 'read index received' (duration: 77.352327ms)","trace[1943551864] 'applied index is now lower than readState.Index' (duration: 72.000838ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:25:20.580895Z","caller":"traceutil/trace.go:171","msg":"trace[910726318] transaction","detail":"{read_only:false; response_revision:19939; number_of_response:1; }","duration":"271.359535ms","start":"2026-05-28T22:25:20.309522Z","end":"2026-05-28T22:25:20.580882Z","steps":["trace[910726318] 'process raft request' (duration: 271.224153ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:20.580977Z","caller":"traceutil/trace.go:171","msg":"trace[676135071] transaction","detail":"{read_only:false; response_revision:19937; number_of_response:1; }","duration":"271.926335ms","start":"2026-05-28T22:25:20.309022Z","end":"2026-05-28T22:25:20.580948Z","steps":["trace[676135071] 'process raft request' (duration: 122.481209ms)","trace[676135071] 'compare' (duration: 148.630431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:25:20.581166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.724142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:25:20.581218Z","caller":"traceutil/trace.go:171","msg":"trace[198207927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19939; }","duration":"149.812713ms","start":"2026-05-28T22:25:20.431393Z","end":"2026-05-28T22:25:20.581205Z","steps":["trace[198207927] 'agreement among raft nodes before linearized reading' (duration: 149.713821ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:20.581616Z","caller":"traceutil/trace.go:171","msg":"trace[1319695770] transaction","detail":"{read_only:false; response_revision:19938; number_of_response:1; }","duration":"272.525977ms","start":"2026-05-28T22:25:20.309058Z","end":"2026-05-28T22:25:20.581584Z","steps":["trace[1319695770] 'process raft request' (duration: 271.388276ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:20.583335Z","caller":"traceutil/trace.go:171","msg":"trace[1698504490] transaction","detail":"{read_only:false; response_revision:19940; number_of_response:1; }","duration":"119.155697ms","start":"2026-05-28T22:25:20.46416Z","end":"2026-05-28T22:25:20.583315Z","steps":["trace[1698504490] 'process raft request' (duration: 119.016994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:25:20.583641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.424492ms","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-28T22:25:20.583693Z","caller":"traceutil/trace.go:171","msg":"trace[269410543] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:19940; }","duration":"118.528054ms","start":"2026-05-28T22:25:20.465151Z","end":"2026-05-28T22:25:20.583679Z","steps":["trace[269410543] 'agreement among raft nodes before linearized reading' (duration: 118.30568ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:25:33.541007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17958} {"level":"info","ts":"2026-05-28T22:25:33.649498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17958,"took":"105.817045ms","hash":1387384729,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":29167616,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-28T22:25:33.649545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1387384729,"revision":17958,"compact-revision":15356} {"level":"info","ts":"2026-05-28T22:29:01.0875Z","caller":"traceutil/trace.go:171","msg":"trace[396267726] transaction","detail":"{read_only:false; response_revision:22266; number_of_response:1; }","duration":"121.741594ms","start":"2026-05-28T22:29:00.965732Z","end":"2026-05-28T22:29:01.087473Z","steps":["trace[396267726] 'process raft request' (duration: 121.316546ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:29:01.251041Z","caller":"traceutil/trace.go:171","msg":"trace[634245688] transaction","detail":"{read_only:false; response_revision:22267; number_of_response:1; }","duration":"160.16459ms","start":"2026-05-28T22:29:01.09086Z","end":"2026-05-28T22:29:01.251024Z","steps":["trace[634245688] 'process raft request' (duration: 160.035817ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:30:33.547429Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20004} {"level":"info","ts":"2026-05-28T22:30:33.654649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20004,"took":"101.418282ms","hash":75399671,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":41189376,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-28T22:30:33.654728Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":75399671,"revision":20004,"compact-revision":17958} {"level":"warn","ts":"2026-05-28T22:32:32.236151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.595827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:32.236275Z","caller":"traceutil/trace.go:171","msg":"trace[1313359856] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24404; }","duration":"151.800381ms","start":"2026-05-28T22:32:32.08445Z","end":"2026-05-28T22:32:32.236251Z","steps":["trace[1313359856] 'count revisions from in-memory index tree' (duration: 151.484546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.534084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.837169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:32.534211Z","caller":"traceutil/trace.go:171","msg":"trace[272616285] linearizableReadLoop","detail":"{readStateIndex:25206; appliedIndex:25205; }","duration":"234.005321ms","start":"2026-05-28T22:32:32.300189Z","end":"2026-05-28T22:32:32.534194Z","steps":["trace[272616285] 'read index received' (duration: 96.820635ms)","trace[272616285] 'applied index is now lower than readState.Index' (duration: 137.183246ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:32.534286Z","caller":"traceutil/trace.go:171","msg":"trace[1619780335] transaction","detail":"{read_only:false; response_revision:24409; number_of_response:1; }","duration":"253.288744ms","start":"2026-05-28T22:32:32.280985Z","end":"2026-05-28T22:32:32.534274Z","steps":["trace[1619780335] 'process raft request' (duration: 116.130639ms)","trace[1619780335] 'compare' (duration: 136.419331ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.534466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.242525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:32.534553Z","caller":"traceutil/trace.go:171","msg":"trace[1764074214] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24409; }","duration":"234.352467ms","start":"2026-05-28T22:32:32.300182Z","end":"2026-05-28T22:32:32.534535Z","steps":["trace[1764074214] 'agreement among raft nodes before linearized reading' (duration: 234.190974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.536956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.610406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:32.537168Z","caller":"traceutil/trace.go:171","msg":"trace[1092576466] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:24410; }","duration":"170.883962ms","start":"2026-05-28T22:32:32.366265Z","end":"2026-05-28T22:32:32.537149Z","steps":["trace[1092576466] 'agreement among raft nodes before linearized reading' (duration: 170.605996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.537247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.054416ms","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-28T22:32:32.537309Z","caller":"traceutil/trace.go:171","msg":"trace[1844387905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24410; }","duration":"143.152728ms","start":"2026-05-28T22:32:32.394139Z","end":"2026-05-28T22:32:32.537292Z","steps":["trace[1844387905] 'agreement among raft nodes before linearized reading' (duration: 142.983365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.537022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.973562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:32.537447Z","caller":"traceutil/trace.go:171","msg":"trace[1700568770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24410; }","duration":"103.460971ms","start":"2026-05-28T22:32:32.433963Z","end":"2026-05-28T22:32:32.537424Z","steps":["trace[1700568770] 'agreement among raft nodes before linearized reading' (duration: 102.966752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:32.5376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.344301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-05-28T22:32:32.537654Z","caller":"traceutil/trace.go:171","msg":"trace[1728578275] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:24410; }","duration":"161.442833ms","start":"2026-05-28T22:32:32.376199Z","end":"2026-05-28T22:32:32.537642Z","steps":["trace[1728578275] 'agreement among raft nodes before linearized reading' (duration: 161.206658ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.809531Z","caller":"traceutil/trace.go:171","msg":"trace[1622418677] linearizableReadLoop","detail":"{readStateIndex:25208; appliedIndex:25207; }","duration":"255.406804ms","start":"2026-05-28T22:32:32.554099Z","end":"2026-05-28T22:32:32.809506Z","steps":["trace[1622418677] 'read index received' (duration: 169.777831ms)","trace[1622418677] 'applied index is now lower than readState.Index' (duration: 85.627893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.809721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.338116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:32.809772Z","caller":"traceutil/trace.go:171","msg":"trace[465612325] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:24411; }","duration":"218.457027ms","start":"2026-05-28T22:32:32.591297Z","end":"2026-05-28T22:32:32.809754Z","steps":["trace[465612325] 'agreement among raft nodes before linearized reading' (duration: 218.356845ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:32.809638Z","caller":"traceutil/trace.go:171","msg":"trace[1071882968] transaction","detail":"{read_only:false; response_revision:24411; number_of_response:1; }","duration":"269.015051ms","start":"2026-05-28T22:32:32.540574Z","end":"2026-05-28T22:32:32.809589Z","steps":["trace[1071882968] 'process raft request' (duration: 183.372267ms)","trace[1071882968] 'compare' (duration: 85.403999ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:32.810273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.135299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-28T22:32:32.810372Z","caller":"traceutil/trace.go:171","msg":"trace[363101065] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:24411; }","duration":"256.293742ms","start":"2026-05-28T22:32:32.554056Z","end":"2026-05-28T22:32:32.81035Z","steps":["trace[363101065] 'agreement among raft nodes before linearized reading' (duration: 255.541207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.116455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.073074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:33.11661Z","caller":"traceutil/trace.go:171","msg":"trace[1839034369] transaction","detail":"{read_only:false; response_revision:24415; number_of_response:1; }","duration":"153.283189ms","start":"2026-05-28T22:32:32.963315Z","end":"2026-05-28T22:32:33.116598Z","steps":["trace[1839034369] 'process raft request' (duration: 153.232598ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.116624Z","caller":"traceutil/trace.go:171","msg":"trace[1236494421] transaction","detail":"{read_only:false; response_revision:24414; number_of_response:1; }","duration":"299.74812ms","start":"2026-05-28T22:32:32.816834Z","end":"2026-05-28T22:32:33.116582Z","steps":["trace[1236494421] 'process raft request' (duration: 145.465222ms)","trace[1236494421] 'compare' (duration: 153.85284ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:33.116729Z","caller":"traceutil/trace.go:171","msg":"trace[39807361] linearizableReadLoop","detail":"{readStateIndex:25211; appliedIndex:25209; }","duration":"298.505486ms","start":"2026-05-28T22:32:32.818216Z","end":"2026-05-28T22:32:33.116721Z","steps":["trace[39807361] 'read index received' (duration: 11.000116ms)","trace[39807361] 'applied index is now lower than readState.Index' (duration: 287.50447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:33.116857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.638789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-05-28T22:32:33.116878Z","caller":"traceutil/trace.go:171","msg":"trace[778640829] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24415; }","duration":"298.659519ms","start":"2026-05-28T22:32:32.818211Z","end":"2026-05-28T22:32:33.11687Z","steps":["trace[778640829] 'agreement among raft nodes before linearized reading' (duration: 298.565638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.11701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.431533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:33.117108Z","caller":"traceutil/trace.go:171","msg":"trace[347867398] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24415; }","duration":"143.616027ms","start":"2026-05-28T22:32:32.973477Z","end":"2026-05-28T22:32:33.117093Z","steps":["trace[347867398] 'agreement among raft nodes before linearized reading' (duration: 143.460834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.11707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.943913ms","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-28T22:32:33.117173Z","caller":"traceutil/trace.go:171","msg":"trace[1687185719] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24415; }","duration":"231.119376ms","start":"2026-05-28T22:32:32.886027Z","end":"2026-05-28T22:32:33.117147Z","steps":["trace[1687185719] 'agreement among raft nodes before linearized reading' (duration: 230.916723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.117203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.250216ms","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-28T22:32:33.117294Z","caller":"traceutil/trace.go:171","msg":"trace[794172470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24415; }","duration":"173.382519ms","start":"2026-05-28T22:32:32.943884Z","end":"2026-05-28T22:32:33.117266Z","steps":["trace[794172470] 'agreement among raft nodes before linearized reading' (duration: 173.091913ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:33.597887Z","caller":"traceutil/trace.go:171","msg":"trace[1314669948] transaction","detail":"{read_only:false; response_revision:24417; number_of_response:1; }","duration":"475.185848ms","start":"2026-05-28T22:32:33.122645Z","end":"2026-05-28T22:32:33.597831Z","steps":["trace[1314669948] 'process raft request' (duration: 474.445934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:33.598093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:33.122621Z","time spent":"475.365231ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:33.747658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.512634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-28T22:32:33.747751Z","caller":"traceutil/trace.go:171","msg":"trace[1963784052] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24417; }","duration":"127.665287ms","start":"2026-05-28T22:32:33.620065Z","end":"2026-05-28T22:32:33.747731Z","steps":["trace[1963784052] 'range keys from in-memory index tree' (duration: 127.283239ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:34.612578Z","caller":"traceutil/trace.go:171","msg":"trace[1856485508] transaction","detail":"{read_only:false; response_revision:24421; number_of_response:1; }","duration":"353.969131ms","start":"2026-05-28T22:32:34.25858Z","end":"2026-05-28T22:32:34.612549Z","steps":["trace[1856485508] 'process raft request' (duration: 346.841307ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:34.612608Z","caller":"traceutil/trace.go:171","msg":"trace[375946036] transaction","detail":"{read_only:false; response_revision:24422; number_of_response:1; }","duration":"353.730347ms","start":"2026-05-28T22:32:34.258868Z","end":"2026-05-28T22:32:34.612599Z","steps":["trace[375946036] 'process raft request' (duration: 353.614225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:34.612741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:34.258541Z","time spent":"354.118794ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:34.612782Z","caller":"traceutil/trace.go:171","msg":"trace[1851474645] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"330.814576ms","start":"2026-05-28T22:32:34.281959Z","end":"2026-05-28T22:32:34.612774Z","steps":["trace[1851474645] 'process raft request' (duration: 330.602502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:34.612821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:34.281939Z","time spent":"330.860277ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:34.61274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:34.258818Z","time spent":"353.841619ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:34.613138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.404337ms","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-28T22:32:34.613227Z","caller":"traceutil/trace.go:171","msg":"trace[229385268] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24423; }","duration":"200.589501ms","start":"2026-05-28T22:32:34.412615Z","end":"2026-05-28T22:32:34.613205Z","steps":["trace[229385268] 'agreement among raft nodes before linearized reading' (duration: 200.338316ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:34.61344Z","caller":"traceutil/trace.go:171","msg":"trace[1914179331] linearizableReadLoop","detail":"{readStateIndex:25220; appliedIndex:25217; }","duration":"200.673582ms","start":"2026-05-28T22:32:34.412714Z","end":"2026-05-28T22:32:34.613388Z","steps":["trace[1914179331] 'read index received' (duration: 192.721122ms)","trace[1914179331] 'applied index is now lower than readState.Index' (duration: 7.297448ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:34.623079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.006734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:34.623174Z","caller":"traceutil/trace.go:171","msg":"trace[1503504600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24424; }","duration":"189.115856ms","start":"2026-05-28T22:32:34.434036Z","end":"2026-05-28T22:32:34.623152Z","steps":["trace[1503504600] 'agreement among raft nodes before linearized reading' (duration: 188.973283ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:34.841211Z","caller":"traceutil/trace.go:171","msg":"trace[410658390] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"213.808841ms","start":"2026-05-28T22:32:34.627367Z","end":"2026-05-28T22:32:34.841176Z","steps":["trace[410658390] 'process raft request' (duration: 131.220073ms)","trace[410658390] 'compare' (duration: 82.085237ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:35.085917Z","caller":"traceutil/trace.go:171","msg":"trace[312289174] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"240.544625ms","start":"2026-05-28T22:32:34.845335Z","end":"2026-05-28T22:32:35.08588Z","steps":["trace[312289174] 'process raft request' (duration: 202.077409ms)","trace[312289174] 'compare' (duration: 38.253672ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:35.086104Z","caller":"traceutil/trace.go:171","msg":"trace[2046587677] linearizableReadLoop","detail":"{readStateIndex:25224; appliedIndex:25222; }","duration":"239.738809ms","start":"2026-05-28T22:32:34.846321Z","end":"2026-05-28T22:32:35.08606Z","steps":["trace[2046587677] 'read index received' (duration: 69.51121ms)","trace[2046587677] 'applied index is now lower than readState.Index' (duration: 170.224919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:35.086478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.127676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-05-28T22:32:35.086569Z","caller":"traceutil/trace.go:171","msg":"trace[1903535627] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24428; }","duration":"240.262189ms","start":"2026-05-28T22:32:34.84629Z","end":"2026-05-28T22:32:35.086553Z","steps":["trace[1903535627] 'agreement among raft nodes before linearized reading' (duration: 239.973534ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:35.086235Z","caller":"traceutil/trace.go:171","msg":"trace[1058693169] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"239.682498ms","start":"2026-05-28T22:32:34.846408Z","end":"2026-05-28T22:32:35.086091Z","steps":["trace[1058693169] 'process raft request' (duration: 239.422773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:35.331861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.362678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:35.332234Z","caller":"traceutil/trace.go:171","msg":"trace[648837756] linearizableReadLoop","detail":"{readStateIndex:25226; appliedIndex:25225; }","duration":"166.326505ms","start":"2026-05-28T22:32:35.165888Z","end":"2026-05-28T22:32:35.332214Z","steps":["trace[648837756] 'read index received' (duration: 62.515198ms)","trace[648837756] 'applied index is now lower than readState.Index' (duration: 103.810227ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:35.332397Z","caller":"traceutil/trace.go:171","msg":"trace[2045051006] transaction","detail":"{read_only:false; response_revision:24429; number_of_response:1; }","duration":"207.104394ms","start":"2026-05-28T22:32:35.125257Z","end":"2026-05-28T22:32:35.332361Z","steps":["trace[2045051006] 'process raft request' (duration: 103.137044ms)","trace[2045051006] 'compare' (duration: 103.042623ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:35.332443Z","caller":"traceutil/trace.go:171","msg":"trace[814140999] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"146.770146ms","start":"2026-05-28T22:32:35.185642Z","end":"2026-05-28T22:32:35.332412Z","steps":["trace[814140999] 'process raft request' (duration: 146.482111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:35.332497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.59578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:35.333347Z","caller":"traceutil/trace.go:171","msg":"trace[973274107] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24430; }","duration":"167.502607ms","start":"2026-05-28T22:32:35.165822Z","end":"2026-05-28T22:32:35.333325Z","steps":["trace[973274107] 'agreement among raft nodes before linearized reading' (duration: 166.563479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:35.646181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.73568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:35.646267Z","caller":"traceutil/trace.go:171","msg":"trace[1145688080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24430; }","duration":"212.849352ms","start":"2026-05-28T22:32:35.433396Z","end":"2026-05-28T22:32:35.646245Z","steps":["trace[1145688080] 'range keys from in-memory index tree' (duration: 212.681749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:35.646265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.884983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:32:35.646361Z","caller":"traceutil/trace.go:171","msg":"trace[929340373] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:24430; }","duration":"213.013605ms","start":"2026-05-28T22:32:35.433327Z","end":"2026-05-28T22:32:35.646341Z","steps":["trace[929340373] 'count revisions from in-memory index tree' (duration: 212.77497ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:36.133253Z","caller":"traceutil/trace.go:171","msg":"trace[1661242811] linearizableReadLoop","detail":"{readStateIndex:25229; appliedIndex:25228; }","duration":"103.241466ms","start":"2026-05-28T22:32:36.029995Z","end":"2026-05-28T22:32:36.133237Z","steps":["trace[1661242811] 'read index received' (duration: 103.134744ms)","trace[1661242811] 'applied index is now lower than readState.Index' (duration: 106.172µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:36.133332Z","caller":"traceutil/trace.go:171","msg":"trace[1940398102] transaction","detail":"{read_only:false; response_revision:24432; number_of_response:1; }","duration":"119.757948ms","start":"2026-05-28T22:32:36.013528Z","end":"2026-05-28T22:32:36.133286Z","steps":["trace[1940398102] 'process raft request' (duration: 119.600005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:36.133437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.400529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:36.133485Z","caller":"traceutil/trace.go:171","msg":"trace[2023443489] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24432; }","duration":"103.496821ms","start":"2026-05-28T22:32:36.029977Z","end":"2026-05-28T22:32:36.133474Z","steps":["trace[2023443489] 'agreement among raft nodes before linearized reading' (duration: 103.396019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:36.409401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.643371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:36.409504Z","caller":"traceutil/trace.go:171","msg":"trace[126561937] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24432; }","duration":"113.834705ms","start":"2026-05-28T22:32:36.295647Z","end":"2026-05-28T22:32:36.409481Z","steps":["trace[126561937] 'count revisions from in-memory index tree' (duration: 113.507818ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:36.519889Z","caller":"traceutil/trace.go:171","msg":"trace[1230423671] transaction","detail":"{read_only:false; response_revision:24433; number_of_response:1; }","duration":"185.026978ms","start":"2026-05-28T22:32:36.334819Z","end":"2026-05-28T22:32:36.519846Z","steps":["trace[1230423671] 'process raft request' (duration: 184.783423ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:36.720409Z","caller":"traceutil/trace.go:171","msg":"trace[199640973] transaction","detail":"{read_only:false; response_revision:24434; number_of_response:1; }","duration":"103.354087ms","start":"2026-05-28T22:32:36.617026Z","end":"2026-05-28T22:32:36.72038Z","steps":["trace[199640973] 'process raft request' (duration: 102.898429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.026237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.357963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:37.026419Z","caller":"traceutil/trace.go:171","msg":"trace[2081601267] transaction","detail":"{read_only:false; response_revision:24435; number_of_response:1; }","duration":"407.869699ms","start":"2026-05-28T22:32:36.618529Z","end":"2026-05-28T22:32:37.026399Z","steps":["trace[2081601267] 'process raft request' (duration: 255.261171ms)","trace[2081601267] 'compare' (duration: 152.248351ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:37.026488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:36.618502Z","time spent":"407.954351ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:37.026781Z","caller":"traceutil/trace.go:171","msg":"trace[154902277] transaction","detail":"{read_only:false; response_revision:24436; number_of_response:1; }","duration":"406.400001ms","start":"2026-05-28T22:32:36.620352Z","end":"2026-05-28T22:32:37.026752Z","steps":["trace[154902277] 'process raft request' (duration: 405.996364ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:37.026856Z","caller":"traceutil/trace.go:171","msg":"trace[1914118445] linearizableReadLoop","detail":"{readStateIndex:25234; appliedIndex:25230; }","duration":"399.213326ms","start":"2026-05-28T22:32:36.627621Z","end":"2026-05-28T22:32:37.026834Z","steps":["trace[1914118445] 'read index received' (duration: 92.268529ms)","trace[1914118445] 'applied index is now lower than readState.Index' (duration: 306.943997ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:37.026936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:36.62034Z","time spent":"406.508893ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:37.026983Z","caller":"traceutil/trace.go:171","msg":"trace[1902171058] transaction","detail":"{read_only:false; response_revision:24438; number_of_response:1; }","duration":"380.559934ms","start":"2026-05-28T22:32:36.646411Z","end":"2026-05-28T22:32:37.026971Z","steps":["trace[1902171058] 'process raft request' (duration: 380.35867ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:37.027064Z","caller":"traceutil/trace.go:171","msg":"trace[408309000] transaction","detail":"{read_only:false; response_revision:24437; number_of_response:1; }","duration":"399.46794ms","start":"2026-05-28T22:32:36.627576Z","end":"2026-05-28T22:32:37.027044Z","steps":["trace[408309000] 'process raft request' (duration: 399.068323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.027094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:36.646371Z","time spent":"380.639885ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:37.027146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:36.62754Z","time spent":"399.564152ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:37.027137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.371193ms","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-28T22:32:37.027218Z","caller":"traceutil/trace.go:171","msg":"trace[766722580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24438; }","duration":"177.502065ms","start":"2026-05-28T22:32:36.849704Z","end":"2026-05-28T22:32:37.027206Z","steps":["trace[766722580] 'agreement among raft nodes before linearized reading' (duration: 177.294262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.02724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.632573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-28T22:32:37.027279Z","caller":"traceutil/trace.go:171","msg":"trace[1744649081] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:24438; }","duration":"399.715835ms","start":"2026-05-28T22:32:36.627551Z","end":"2026-05-28T22:32:37.027266Z","steps":["trace[1744649081] 'agreement among raft nodes before linearized reading' (duration: 399.620383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.027311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:36.627524Z","time spent":"399.780386ms","remote":"127.0.0.1:41840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-05-28T22:32:37.027349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.774734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T22:32:37.027379Z","caller":"traceutil/trace.go:171","msg":"trace[623254152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24438; }","duration":"105.850605ms","start":"2026-05-28T22:32:36.921518Z","end":"2026-05-28T22:32:37.027369Z","steps":["trace[623254152] 'agreement among raft nodes before linearized reading' (duration: 105.717443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.417493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.466459ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-28T22:32:37.417805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.028447Z","time spent":"389.35223ms","remote":"127.0.0.1:41840","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-28T22:32:37.418384Z","caller":"traceutil/trace.go:171","msg":"trace[605923032] transaction","detail":"{read_only:false; response_revision:24439; number_of_response:1; }","duration":"386.626588ms","start":"2026-05-28T22:32:37.031738Z","end":"2026-05-28T22:32:37.418364Z","steps":["trace[605923032] 'process raft request' (duration: 386.011196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.418501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.03172Z","time spent":"386.712259ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:37.418598Z","caller":"traceutil/trace.go:171","msg":"trace[2025311224] linearizableReadLoop","detail":"{readStateIndex:25238; appliedIndex:25235; }","duration":"327.450863ms","start":"2026-05-28T22:32:37.09112Z","end":"2026-05-28T22:32:37.418571Z","steps":["trace[2025311224] 'read index received' (duration: 73.700289ms)","trace[2025311224] 'applied index is now lower than readState.Index' (duration: 253.749364ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:37.418935Z","caller":"traceutil/trace.go:171","msg":"trace[843293223] transaction","detail":"{read_only:false; response_revision:24440; number_of_response:1; }","duration":"387.135547ms","start":"2026-05-28T22:32:37.031763Z","end":"2026-05-28T22:32:37.418898Z","steps":["trace[843293223] 'process raft request' (duration: 386.558396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.418988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.8718ms","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-28T22:32:37.419032Z","caller":"traceutil/trace.go:171","msg":"trace[1658440744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24440; }","duration":"327.961322ms","start":"2026-05-28T22:32:37.091059Z","end":"2026-05-28T22:32:37.41902Z","steps":["trace[1658440744] 'agreement among raft nodes before linearized reading' (duration: 327.798579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.419072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.091034Z","time spent":"328.029393ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T22:32:37.419116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.031743Z","time spent":"387.27471ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:37.446704Z","caller":"traceutil/trace.go:171","msg":"trace[1717936460] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"107.520008ms","start":"2026-05-28T22:32:37.339161Z","end":"2026-05-28T22:32:37.446681Z","steps":["trace[1717936460] 'process raft request' (duration: 107.432736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.446929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.942931ms","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-28T22:32:37.447013Z","caller":"traceutil/trace.go:171","msg":"trace[1295529216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24441; }","duration":"355.083824ms","start":"2026-05-28T22:32:37.09191Z","end":"2026-05-28T22:32:37.446994Z","steps":["trace[1295529216] 'agreement among raft nodes before linearized reading' (duration: 354.86495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.44706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.091888Z","time spent":"355.162965ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T22:32:37.447218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.021755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-28T22:32:37.447399Z","caller":"traceutil/trace.go:171","msg":"trace[1737159259] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24441; }","duration":"109.214509ms","start":"2026-05-28T22:32:37.338103Z","end":"2026-05-28T22:32:37.447318Z","steps":["trace[1737159259] 'agreement among raft nodes before linearized reading' (duration: 108.668698ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:37.637287Z","caller":"traceutil/trace.go:171","msg":"trace[290035118] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"217.678434ms","start":"2026-05-28T22:32:37.419571Z","end":"2026-05-28T22:32:37.637249Z","steps":["trace[290035118] 'process raft request' (duration: 155.9479ms)","trace[290035118] 'compare' (duration: 61.026991ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:37.6373Z","caller":"traceutil/trace.go:171","msg":"trace[2063866360] transaction","detail":"{read_only:false; response_revision:24443; number_of_response:1; }","duration":"213.508394ms","start":"2026-05-28T22:32:37.423758Z","end":"2026-05-28T22:32:37.637266Z","steps":["trace[2063866360] 'process raft request' (duration: 213.368082ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:37.637395Z","caller":"traceutil/trace.go:171","msg":"trace[1163302334] linearizableReadLoop","detail":"{readStateIndex:25241; appliedIndex:25239; }","duration":"190.615533ms","start":"2026-05-28T22:32:37.446755Z","end":"2026-05-28T22:32:37.63737Z","steps":["trace[1163302334] 'read index received' (duration: 128.777628ms)","trace[1163302334] 'applied index is now lower than readState.Index' (duration: 61.834905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:37.637819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.601188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:37.63792Z","caller":"traceutil/trace.go:171","msg":"trace[777409382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24443; }","duration":"203.778062ms","start":"2026-05-28T22:32:37.43412Z","end":"2026-05-28T22:32:37.637898Z","steps":["trace[777409382] 'agreement among raft nodes before linearized reading' (duration: 203.557478ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:37.810263Z","caller":"traceutil/trace.go:171","msg":"trace[1859452945] transaction","detail":"{read_only:false; response_revision:24444; number_of_response:1; }","duration":"358.986228ms","start":"2026-05-28T22:32:37.451245Z","end":"2026-05-28T22:32:37.810232Z","steps":["trace[1859452945] 'process raft request' (duration: 331.425178ms)","trace[1859452945] 'compare' (duration: 27.334935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:37.810464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.451227Z","time spent":"359.13125ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:37.810445Z","caller":"traceutil/trace.go:171","msg":"trace[507300364] linearizableReadLoop","detail":"{readStateIndex:25243; appliedIndex:25241; }","duration":"172.970861ms","start":"2026-05-28T22:32:37.637443Z","end":"2026-05-28T22:32:37.810413Z","steps":["trace[507300364] 'read index received' (duration: 145.203608ms)","trace[507300364] 'applied index is now lower than readState.Index' (duration: 27.764923ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:37.810589Z","caller":"traceutil/trace.go:171","msg":"trace[1831219491] transaction","detail":"{read_only:false; response_revision:24445; number_of_response:1; }","duration":"358.838414ms","start":"2026-05-28T22:32:37.451726Z","end":"2026-05-28T22:32:37.810564Z","steps":["trace[1831219491] 'process raft request' (duration: 358.454076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.810656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.574643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:37.810705Z","caller":"traceutil/trace.go:171","msg":"trace[1438218194] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:24445; }","duration":"303.697495ms","start":"2026-05-28T22:32:37.506995Z","end":"2026-05-28T22:32:37.810693Z","steps":["trace[1438218194] 'agreement among raft nodes before linearized reading' (duration: 303.578883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.810736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.45171Z","time spent":"358.946656ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:37.810755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:37.506974Z","time spent":"303.765756ms","remote":"127.0.0.1:33496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:37.810769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.627747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-28T22:32:37.810811Z","caller":"traceutil/trace.go:171","msg":"trace[473926354] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24445; }","duration":"170.704428ms","start":"2026-05-28T22:32:37.640095Z","end":"2026-05-28T22:32:37.8108Z","steps":["trace[473926354] 'agreement among raft nodes before linearized reading' (duration: 170.547625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:37.811143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.821771ms","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-28T22:32:37.811178Z","caller":"traceutil/trace.go:171","msg":"trace[118860851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24445; }","duration":"118.896212ms","start":"2026-05-28T22:32:37.692271Z","end":"2026-05-28T22:32:37.811167Z","steps":["trace[118860851] 'agreement among raft nodes before linearized reading' (duration: 118.738409ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:38.252156Z","caller":"traceutil/trace.go:171","msg":"trace[1306385613] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"113.438658ms","start":"2026-05-28T22:32:38.138691Z","end":"2026-05-28T22:32:38.25213Z","steps":["trace[1306385613] 'process raft request' (duration: 113.254044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:44.934332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296567356711339786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T22:32:44.961658Z","caller":"traceutil/trace.go:171","msg":"trace[24073382] transaction","detail":"{read_only:false; response_revision:24493; number_of_response:1; }","duration":"685.41928ms","start":"2026-05-28T22:32:44.276211Z","end":"2026-05-28T22:32:44.961631Z","steps":["trace[24073382] 'process raft request' (duration: 685.226876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:44.961834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:44.276185Z","time spent":"685.558302ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:45.257283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.603785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:45.257591Z","caller":"traceutil/trace.go:171","msg":"trace[878278157] linearizableReadLoop","detail":"{readStateIndex:25294; appliedIndex:25291; }","duration":"824.253057ms","start":"2026-05-28T22:32:44.433319Z","end":"2026-05-28T22:32:45.257572Z","steps":["trace[878278157] 'read index received' (duration: 528.175626ms)","trace[878278157] 'applied index is now lower than readState.Index' (duration: 296.076251ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:45.257619Z","caller":"traceutil/trace.go:171","msg":"trace[246173240] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"708.940483ms","start":"2026-05-28T22:32:44.54862Z","end":"2026-05-28T22:32:45.257561Z","steps":["trace[246173240] 'process raft request' (duration: 441.968411ms)","trace[246173240] 'compare' (duration: 266.468223ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:45.257743Z","caller":"traceutil/trace.go:171","msg":"trace[142849030] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"511.745256ms","start":"2026-05-28T22:32:44.745873Z","end":"2026-05-28T22:32:45.257618Z","steps":["trace[142849030] 'process raft request' (duration: 511.520782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.257747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.41104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:45.257982Z","caller":"traceutil/trace.go:171","msg":"trace[727715604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24495; }","duration":"824.701885ms","start":"2026-05-28T22:32:44.433258Z","end":"2026-05-28T22:32:45.25796Z","steps":["trace[727715604] 'agreement among raft nodes before linearized reading' (duration: 824.4054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.258039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:44.43322Z","time spent":"824.807898ms","remote":"127.0.0.1:41814","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-28T22:32:45.257818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:44.54859Z","time spent":"709.115077ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:45.258163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:44.74585Z","time spent":"512.023832ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:45.267925Z","caller":"traceutil/trace.go:171","msg":"trace[1365599948] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"215.312698ms","start":"2026-05-28T22:32:45.052588Z","end":"2026-05-28T22:32:45.267901Z","steps":["trace[1365599948] 'process raft request' (duration: 215.134815ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:45.304754Z","caller":"traceutil/trace.go:171","msg":"trace[1646453615] transaction","detail":"{read_only:false; response_revision:24498; number_of_response:1; }","duration":"247.26822ms","start":"2026-05-28T22:32:45.057453Z","end":"2026-05-28T22:32:45.304721Z","steps":["trace[1646453615] 'process raft request' (duration: 247.058176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.30484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.300219ms","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-28T22:32:45.304887Z","caller":"traceutil/trace.go:171","msg":"trace[665857279] transaction","detail":"{read_only:false; response_revision:24497; number_of_response:1; }","duration":"249.067555ms","start":"2026-05-28T22:32:45.055792Z","end":"2026-05-28T22:32:45.304859Z","steps":["trace[665857279] 'process raft request' (duration: 248.566555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.304858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.886911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:45.30494Z","caller":"traceutil/trace.go:171","msg":"trace[1055948120] 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:24498; }","duration":"189.447832ms","start":"2026-05-28T22:32:45.11547Z","end":"2026-05-28T22:32:45.304917Z","steps":["trace[1055948120] 'agreement among raft nodes before linearized reading' (duration: 189.261858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.304766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.414264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:45.305166Z","caller":"traceutil/trace.go:171","msg":"trace[744841108] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24498; }","duration":"403.857622ms","start":"2026-05-28T22:32:44.901255Z","end":"2026-05-28T22:32:45.305112Z","steps":["trace[744841108] 'agreement among raft nodes before linearized reading' (duration: 403.333563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.305317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:44.90123Z","time spent":"404.029185ms","remote":"127.0.0.1:42034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:45.305051Z","caller":"traceutil/trace.go:171","msg":"trace[381198803] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24498; }","duration":"336.028124ms","start":"2026-05-28T22:32:44.968934Z","end":"2026-05-28T22:32:45.304962Z","steps":["trace[381198803] 'agreement among raft nodes before linearized reading' (duration: 335.84043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.306026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:44.968869Z","time spent":"337.132565ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:45.663953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.833891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-28T22:32:45.664001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.999536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:45.664094Z","caller":"traceutil/trace.go:171","msg":"trace[1022833552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24498; }","duration":"230.100178ms","start":"2026-05-28T22:32:45.433967Z","end":"2026-05-28T22:32:45.664067Z","steps":["trace[1022833552] 'range keys from in-memory index tree' (duration: 229.867403ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:45.664046Z","caller":"traceutil/trace.go:171","msg":"trace[457851369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24498; }","duration":"187.939993ms","start":"2026-05-28T22:32:45.476083Z","end":"2026-05-28T22:32:45.664023Z","steps":["trace[457851369] 'range keys from in-memory index tree' (duration: 187.653557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:45.664166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.142663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T22:32:45.664261Z","caller":"traceutil/trace.go:171","msg":"trace[451923275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24498; }","duration":"209.322076ms","start":"2026-05-28T22:32:45.454917Z","end":"2026-05-28T22:32:45.664239Z","steps":["trace[451923275] 'range keys from in-memory index tree' (duration: 208.937139ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:45.664768Z","caller":"traceutil/trace.go:171","msg":"trace[1725454230] linearizableReadLoop","detail":"{readStateIndex:25298; appliedIndex:25297; }","duration":"127.686626ms","start":"2026-05-28T22:32:45.536931Z","end":"2026-05-28T22:32:45.664618Z","steps":["trace[1725454230] 'read index received' (duration: 115.485756ms)","trace[1725454230] 'applied index is now lower than readState.Index' (duration: 12.19946ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:45.664914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.945041ms","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-28T22:32:45.664989Z","caller":"traceutil/trace.go:171","msg":"trace[660235913] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24499; }","duration":"128.051763ms","start":"2026-05-28T22:32:45.536892Z","end":"2026-05-28T22:32:45.664944Z","steps":["trace[660235913] 'agreement among raft nodes before linearized reading' (duration: 127.933651ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:45.665013Z","caller":"traceutil/trace.go:171","msg":"trace[113909524] transaction","detail":"{read_only:false; response_revision:24499; number_of_response:1; }","duration":"187.80745ms","start":"2026-05-28T22:32:45.477124Z","end":"2026-05-28T22:32:45.664932Z","steps":["trace[113909524] 'process raft request' (duration: 175.389996ms)","trace[113909524] 'compare' (duration: 11.279502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:45.665182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.46324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-28T22:32:45.665235Z","caller":"traceutil/trace.go:171","msg":"trace[577837871] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24498; }","duration":"190.543003ms","start":"2026-05-28T22:32:45.47468Z","end":"2026-05-28T22:32:45.665223Z","steps":["trace[577837871] 'range keys from in-memory index tree' (duration: 189.819777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.206665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.163235ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:46.206818Z","caller":"traceutil/trace.go:171","msg":"trace[1813352495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24499; }","duration":"208.342228ms","start":"2026-05-28T22:32:45.998455Z","end":"2026-05-28T22:32:46.206798Z","steps":["trace[1813352495] 'range keys from in-memory index tree' (duration: 208.142684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.207301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.974368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:46.207631Z","caller":"traceutil/trace.go:171","msg":"trace[1932514042] linearizableReadLoop","detail":"{readStateIndex:25300; appliedIndex:25298; }","duration":"537.532223ms","start":"2026-05-28T22:32:45.670046Z","end":"2026-05-28T22:32:46.207579Z","steps":["trace[1932514042] 'read index received' (duration: 257.089927ms)","trace[1932514042] 'applied index is now lower than readState.Index' (duration: 280.441246ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:46.207764Z","caller":"traceutil/trace.go:171","msg":"trace[14185106] transaction","detail":"{read_only:false; response_revision:24501; number_of_response:1; }","duration":"538.147704ms","start":"2026-05-28T22:32:45.669579Z","end":"2026-05-28T22:32:46.207727Z","steps":["trace[14185106] 'process raft request' (duration: 537.870729ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:46.2078Z","caller":"traceutil/trace.go:171","msg":"trace[1247601630] transaction","detail":"{read_only:false; response_revision:24500; number_of_response:1; }","duration":"538.194955ms","start":"2026-05-28T22:32:45.669549Z","end":"2026-05-28T22:32:46.207744Z","steps":["trace[1247601630] 'process raft request' (duration: 257.572305ms)","trace[1247601630] 'compare' (duration: 279.339646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:46.207864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.66955Z","time spent":"538.267446ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:46.207988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.669525Z","time spent":"538.359318ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:46.20809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.870507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-05-28T22:32:46.208145Z","caller":"traceutil/trace.go:171","msg":"trace[487138822] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24501; }","duration":"313.950538ms","start":"2026-05-28T22:32:45.894181Z","end":"2026-05-28T22:32:46.208132Z","steps":["trace[487138822] 'agreement among raft nodes before linearized reading' (duration: 313.650272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.20819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.894173Z","time spent":"314.007029ms","remote":"127.0.0.1:54188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418347,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-05-28T22:32:46.208245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.276371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:46.208295Z","caller":"traceutil/trace.go:171","msg":"trace[1384493736] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24501; }","duration":"308.354353ms","start":"2026-05-28T22:32:45.899928Z","end":"2026-05-28T22:32:46.208283Z","steps":["trace[1384493736] 'agreement among raft nodes before linearized reading' (duration: 308.270681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.20828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.449622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-28T22:32:46.208335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.899915Z","time spent":"308.410723ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-05-28T22:32:46.208328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.536792ms","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-28T22:32:46.208376Z","caller":"traceutil/trace.go:171","msg":"trace[44737403] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24501; }","duration":"136.593624ms","start":"2026-05-28T22:32:46.071763Z","end":"2026-05-28T22:32:46.208356Z","steps":["trace[44737403] 'agreement among raft nodes before linearized reading' (duration: 136.411631ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:46.208392Z","caller":"traceutil/trace.go:171","msg":"trace[191596788] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24501; }","duration":"363.620913ms","start":"2026-05-28T22:32:45.84475Z","end":"2026-05-28T22:32:46.208371Z","steps":["trace[191596788] 'agreement among raft nodes before linearized reading' (duration: 363.45155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.208438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.844741Z","time spent":"363.686584ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-28T22:32:46.208487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.818672ms","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-28T22:32:46.208493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.252283ms","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-28T22:32:46.20852Z","caller":"traceutil/trace.go:171","msg":"trace[565279180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24501; }","duration":"315.889724ms","start":"2026-05-28T22:32:45.892622Z","end":"2026-05-28T22:32:46.208512Z","steps":["trace[565279180] 'agreement among raft nodes before linearized reading' (duration: 315.784342ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:46.208537Z","caller":"traceutil/trace.go:171","msg":"trace[1219906218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24501; }","duration":"366.342805ms","start":"2026-05-28T22:32:45.842182Z","end":"2026-05-28T22:32:46.208524Z","steps":["trace[1219906218] 'agreement among raft nodes before linearized reading' (duration: 366.163612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.208572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.842155Z","time spent":"366.409986ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T22:32:46.208616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.892572Z","time spent":"315.974066ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T22:32:46.208264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.072963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-05-28T22:32:46.20891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.978964ms","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-28T22:32:46.208985Z","caller":"traceutil/trace.go:171","msg":"trace[784517542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24501; }","duration":"518.081085ms","start":"2026-05-28T22:32:45.690889Z","end":"2026-05-28T22:32:46.20897Z","steps":["trace[784517542] 'agreement among raft nodes before linearized reading' (duration: 516.896592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.209024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.690877Z","time spent":"518.137787ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T22:32:46.209023Z","caller":"traceutil/trace.go:171","msg":"trace[1549534499] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24501; }","duration":"538.918119ms","start":"2026-05-28T22:32:45.670022Z","end":"2026-05-28T22:32:46.20894Z","steps":["trace[1549534499] 'agreement among raft nodes before linearized reading' (duration: 537.711956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.209154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:45.670011Z","time spent":"539.123933ms","remote":"127.0.0.1:41976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-28T22:32:46.438286Z","caller":"traceutil/trace.go:171","msg":"trace[1457020791] transaction","detail":"{read_only:false; response_revision:24502; number_of_response:1; }","duration":"224.754327ms","start":"2026-05-28T22:32:46.213509Z","end":"2026-05-28T22:32:46.438263Z","steps":["trace[1457020791] 'process raft request' (duration: 221.655268ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:46.440592Z","caller":"traceutil/trace.go:171","msg":"trace[932733539] transaction","detail":"{read_only:false; response_revision:24503; number_of_response:1; }","duration":"226.52458ms","start":"2026-05-28T22:32:46.214047Z","end":"2026-05-28T22:32:46.440572Z","steps":["trace[932733539] 'process raft request' (duration: 226.366868ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:46.44079Z","caller":"traceutil/trace.go:171","msg":"trace[364604425] transaction","detail":"{read_only:false; response_revision:24504; number_of_response:1; }","duration":"226.614252ms","start":"2026-05-28T22:32:46.214153Z","end":"2026-05-28T22:32:46.440767Z","steps":["trace[364604425] 'process raft request' (duration: 226.379418ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:46.44084Z","caller":"traceutil/trace.go:171","msg":"trace[1888812390] linearizableReadLoop","detail":"{readStateIndex:25304; appliedIndex:25300; }","duration":"170.502593ms","start":"2026-05-28T22:32:46.270318Z","end":"2026-05-28T22:32:46.440821Z","steps":["trace[1888812390] 'read index received' (duration: 164.920247ms)","trace[1888812390] 'applied index is now lower than readState.Index' (duration: 5.581586ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:46.440881Z","caller":"traceutil/trace.go:171","msg":"trace[1042379992] transaction","detail":"{read_only:false; response_revision:24505; number_of_response:1; }","duration":"226.108842ms","start":"2026-05-28T22:32:46.214738Z","end":"2026-05-28T22:32:46.440847Z","steps":["trace[1042379992] 'process raft request' (duration: 225.978929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:46.440959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.622885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:46.440984Z","caller":"traceutil/trace.go:171","msg":"trace[1477814565] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24505; }","duration":"170.720168ms","start":"2026-05-28T22:32:46.270257Z","end":"2026-05-28T22:32:46.440977Z","steps":["trace[1477814565] 'agreement among raft nodes before linearized reading' (duration: 170.640727ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:51.468879Z","caller":"traceutil/trace.go:171","msg":"trace[606685888] transaction","detail":"{read_only:false; response_revision:24595; number_of_response:1; }","duration":"189.467081ms","start":"2026-05-28T22:32:51.279395Z","end":"2026-05-28T22:32:51.468862Z","steps":["trace[606685888] 'process raft request' (duration: 189.343909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.686832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.747551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:51.687069Z","caller":"traceutil/trace.go:171","msg":"trace[2034512982] transaction","detail":"{read_only:false; response_revision:24598; number_of_response:1; }","duration":"364.323768ms","start":"2026-05-28T22:32:51.322732Z","end":"2026-05-28T22:32:51.687055Z","steps":["trace[2034512982] 'process raft request' (duration: 364.274747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.687122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.322726Z","time spent":"364.375619ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:51.687131Z","caller":"traceutil/trace.go:171","msg":"trace[2057200643] transaction","detail":"{read_only:false; response_revision:24596; number_of_response:1; }","duration":"404.021166ms","start":"2026-05-28T22:32:51.283083Z","end":"2026-05-28T22:32:51.687104Z","steps":["trace[2057200643] 'process raft request' (duration: 286.915248ms)","trace[2057200643] 'compare' (duration: 116.553918ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:51.687254Z","caller":"traceutil/trace.go:171","msg":"trace[1371255871] transaction","detail":"{read_only:false; response_revision:24597; number_of_response:1; }","duration":"394.73391ms","start":"2026-05-28T22:32:51.292515Z","end":"2026-05-28T22:32:51.687249Z","steps":["trace[1371255871] 'process raft request' (duration: 394.416865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.687275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.283053Z","time spent":"404.144518ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:51.687289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.292509Z","time spent":"394.762141ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:32:51.687351Z","caller":"traceutil/trace.go:171","msg":"trace[1495549265] linearizableReadLoop","detail":"{readStateIndex:25399; appliedIndex:25394; }","duration":"335.608176ms","start":"2026-05-28T22:32:51.351722Z","end":"2026-05-28T22:32:51.68733Z","steps":["trace[1495549265] 'read index received' (duration: 117.084077ms)","trace[1495549265] 'applied index is now lower than readState.Index' (duration: 218.522069ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:51.687384Z","caller":"traceutil/trace.go:171","msg":"trace[1551210964] transaction","detail":"{read_only:false; response_revision:24599; number_of_response:1; }","duration":"361.635217ms","start":"2026-05-28T22:32:51.325731Z","end":"2026-05-28T22:32:51.687366Z","steps":["trace[1551210964] 'process raft request' (duration: 361.2967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.687488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.325698Z","time spent":"361.736539ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:32:51.687618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.866561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07b5aceb-75b4-4071-9d41-d1069522f12f\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-28T22:32:51.687711Z","caller":"traceutil/trace.go:171","msg":"trace[875376561] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-07b5aceb-75b4-4071-9d41-d1069522f12f; range_end:; response_count:1; response_revision:24599; }","duration":"336.015254ms","start":"2026-05-28T22:32:51.351675Z","end":"2026-05-28T22:32:51.68769Z","steps":["trace[875376561] 'agreement among raft nodes before linearized reading' (duration: 335.731238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.687763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.35166Z","time spent":"336.093336ms","remote":"127.0.0.1:41976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-07b5aceb-75b4-4071-9d41-d1069522f12f\" "} {"level":"warn","ts":"2026-05-28T22:32:51.982698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.417516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:32:51.982841Z","caller":"traceutil/trace.go:171","msg":"trace[1745105253] linearizableReadLoop","detail":"{readStateIndex:25400; appliedIndex:25399; }","duration":"295.437719ms","start":"2026-05-28T22:32:51.687383Z","end":"2026-05-28T22:32:51.982821Z","steps":["trace[1745105253] 'read index received' (duration: 127.669587ms)","trace[1745105253] 'applied index is now lower than readState.Index' (duration: 167.766742ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:32:51.983067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.855804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-28T22:32:51.983099Z","caller":"traceutil/trace.go:171","msg":"trace[1609091306] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24600; }","duration":"516.922204ms","start":"2026-05-28T22:32:51.466166Z","end":"2026-05-28T22:32:51.983088Z","steps":["trace[1609091306] 'agreement among raft nodes before linearized reading' (duration: 516.765001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.983135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.466142Z","time spent":"516.984606ms","remote":"127.0.0.1:41976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-28T22:32:51.983249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.219007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-05-28T22:32:51.983314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.225025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:51.983303Z","caller":"traceutil/trace.go:171","msg":"trace[328526894] transaction","detail":"{read_only:false; response_revision:24600; number_of_response:1; }","duration":"297.889755ms","start":"2026-05-28T22:32:51.685382Z","end":"2026-05-28T22:32:51.983271Z","steps":["trace[328526894] 'process raft request' (duration: 129.782786ms)","trace[328526894] 'compare' (duration: 167.212462ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:51.983352Z","caller":"traceutil/trace.go:171","msg":"trace[1772406652] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24600; }","duration":"330.325727ms","start":"2026-05-28T22:32:51.653015Z","end":"2026-05-28T22:32:51.983341Z","steps":["trace[1772406652] 'agreement among raft nodes before linearized reading' (duration: 330.248185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.983382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.652995Z","time spent":"330.381138ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:51.983334Z","caller":"traceutil/trace.go:171","msg":"trace[913089544] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24600; }","duration":"563.45113ms","start":"2026-05-28T22:32:51.419864Z","end":"2026-05-28T22:32:51.983315Z","steps":["trace[913089544] 'agreement among raft nodes before linearized reading' (duration: 563.160745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.983114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.758043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-05-28T22:32:51.98343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.419846Z","time spent":"563.574223ms","remote":"127.0.0.1:41934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" "} {"level":"warn","ts":"2026-05-28T22:32:51.98343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.918379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:32:51.983545Z","caller":"traceutil/trace.go:171","msg":"trace[1335893278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24600; }","duration":"548.085082ms","start":"2026-05-28T22:32:51.435436Z","end":"2026-05-28T22:32:51.983521Z","steps":["trace[1335893278] 'agreement among raft nodes before linearized reading' (duration: 547.866358ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:51.983547Z","caller":"traceutil/trace.go:171","msg":"trace[13028252] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:24600; }","duration":"411.222602ms","start":"2026-05-28T22:32:51.572283Z","end":"2026-05-28T22:32:51.983506Z","steps":["trace[13028252] 'agreement among raft nodes before linearized reading' (duration: 410.59001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:51.983599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.435419Z","time spent":"548.171604ms","remote":"127.0.0.1:41802","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-28T22:32:51.983634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:32:51.57226Z","time spent":"411.331274ms","remote":"127.0.0.1:42138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":181,"response size":32,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-28T22:32:52.121426Z","caller":"traceutil/trace.go:171","msg":"trace[984775131] linearizableReadLoop","detail":"{readStateIndex:25401; appliedIndex:25400; }","duration":"126.980823ms","start":"2026-05-28T22:32:51.994411Z","end":"2026-05-28T22:32:52.121391Z","steps":["trace[984775131] 'read index received' (duration: 126.698748ms)","trace[984775131] 'applied index is now lower than readState.Index' (duration: 280.475µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:52.121477Z","caller":"traceutil/trace.go:171","msg":"trace[705565188] transaction","detail":"{read_only:false; response_revision:24601; number_of_response:1; }","duration":"129.47136ms","start":"2026-05-28T22:32:51.991973Z","end":"2026-05-28T22:32:52.121444Z","steps":["trace[705565188] 'process raft request' (duration: 129.181744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:52.12174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.299529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f400f6bd-d639-485d-a8ad-15a41c6a1db0\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-05-28T22:32:52.121859Z","caller":"traceutil/trace.go:171","msg":"trace[872216612] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f400f6bd-d639-485d-a8ad-15a41c6a1db0; range_end:; response_count:1; response_revision:24601; }","duration":"127.460942ms","start":"2026-05-28T22:32:51.994348Z","end":"2026-05-28T22:32:52.121809Z","steps":["trace[872216612] 'agreement among raft nodes before linearized reading' (duration: 127.159887ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:52.431565Z","caller":"traceutil/trace.go:171","msg":"trace[2080375482] transaction","detail":"{read_only:false; response_revision:24602; number_of_response:1; }","duration":"115.470386ms","start":"2026-05-28T22:32:52.31606Z","end":"2026-05-28T22:32:52.43153Z","steps":["trace[2080375482] 'process raft request' (duration: 115.250142ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:52.449186Z","caller":"traceutil/trace.go:171","msg":"trace[2028096820] transaction","detail":"{read_only:false; response_revision:24603; number_of_response:1; }","duration":"132.862704ms","start":"2026-05-28T22:32:52.316298Z","end":"2026-05-28T22:32:52.449161Z","steps":["trace[2028096820] 'process raft request' (duration: 132.687201ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:58.259206Z","caller":"traceutil/trace.go:171","msg":"trace[1639382719] linearizableReadLoop","detail":"{readStateIndex:25468; appliedIndex:25467; }","duration":"167.672291ms","start":"2026-05-28T22:32:58.091502Z","end":"2026-05-28T22:32:58.259174Z","steps":["trace[1639382719] 'read index received' (duration: 167.505848ms)","trace[1639382719] 'applied index is now lower than readState.Index' (duration: 164.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:32:58.259349Z","caller":"traceutil/trace.go:171","msg":"trace[1037479437] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"256.285781ms","start":"2026-05-28T22:32:58.002955Z","end":"2026-05-28T22:32:58.25924Z","steps":["trace[1037479437] 'process raft request' (duration: 256.014836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:32:58.259556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.029477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-05-28T22:32:58.259652Z","caller":"traceutil/trace.go:171","msg":"trace[1971139733] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24665; }","duration":"168.18847ms","start":"2026-05-28T22:32:58.091443Z","end":"2026-05-28T22:32:58.259631Z","steps":["trace[1971139733] 'agreement among raft nodes before linearized reading' (duration: 167.892875ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:32:58.420175Z","caller":"traceutil/trace.go:171","msg":"trace[787248923] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"140.288245ms","start":"2026-05-28T22:32:58.27986Z","end":"2026-05-28T22:32:58.420148Z","steps":["trace[787248923] 'process raft request' (duration: 140.0555ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:05.15186Z","caller":"traceutil/trace.go:171","msg":"trace[829741689] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"120.669514ms","start":"2026-05-28T22:33:05.03116Z","end":"2026-05-28T22:33:05.15183Z","steps":["trace[829741689] 'process raft request' (duration: 120.487481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:05.310078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.070381ms","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-28T22:33:05.310157Z","caller":"traceutil/trace.go:171","msg":"trace[372069480] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24725; }","duration":"104.200864ms","start":"2026-05-28T22:33:05.20594Z","end":"2026-05-28T22:33:05.310141Z","steps":["trace[372069480] 'count revisions from in-memory index tree' (duration: 103.99265ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:05.449426Z","caller":"traceutil/trace.go:171","msg":"trace[1260967960] linearizableReadLoop","detail":"{readStateIndex:25530; appliedIndex:25529; }","duration":"110.81029ms","start":"2026-05-28T22:33:05.338595Z","end":"2026-05-28T22:33:05.449405Z","steps":["trace[1260967960] 'read index received' (duration: 110.656427ms)","trace[1260967960] 'applied index is now lower than readState.Index' (duration: 153.243µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:05.44954Z","caller":"traceutil/trace.go:171","msg":"trace[1921322546] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"114.085591ms","start":"2026-05-28T22:33:05.335437Z","end":"2026-05-28T22:33:05.449522Z","steps":["trace[1921322546] 'process raft request' (duration: 113.821146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:05.449708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.087575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-28T22:33:05.449739Z","caller":"traceutil/trace.go:171","msg":"trace[612175340] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24726; }","duration":"111.159566ms","start":"2026-05-28T22:33:05.338572Z","end":"2026-05-28T22:33:05.449731Z","steps":["trace[612175340] 'agreement among raft nodes before linearized reading' (duration: 110.956442ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:05.717731Z","caller":"traceutil/trace.go:171","msg":"trace[806097378] linearizableReadLoop","detail":"{readStateIndex:25531; appliedIndex:25530; }","duration":"258.35153ms","start":"2026-05-28T22:33:05.459349Z","end":"2026-05-28T22:33:05.717701Z","steps":["trace[806097378] 'read index received' (duration: 250.204736ms)","trace[806097378] 'applied index is now lower than readState.Index' (duration: 8.145514ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:05.717823Z","caller":"traceutil/trace.go:171","msg":"trace[742178759] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"261.354277ms","start":"2026-05-28T22:33:05.456443Z","end":"2026-05-28T22:33:05.717797Z","steps":["trace[742178759] 'process raft request' (duration: 253.094471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:05.71794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.561244ms","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-28T22:33:05.718036Z","caller":"traceutil/trace.go:171","msg":"trace[727987062] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24727; }","duration":"258.724417ms","start":"2026-05-28T22:33:05.459291Z","end":"2026-05-28T22:33:05.718015Z","steps":["trace[727987062] 'agreement among raft nodes before linearized reading' (duration: 258.561294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:05.721725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.088657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-28T22:33:05.721789Z","caller":"traceutil/trace.go:171","msg":"trace[1388567303] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"209.337405ms","start":"2026-05-28T22:33:05.512434Z","end":"2026-05-28T22:33:05.721772Z","steps":["trace[1388567303] 'process raft request' (duration: 209.0211ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:05.72181Z","caller":"traceutil/trace.go:171","msg":"trace[2141470769] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24728; }","duration":"256.204989ms","start":"2026-05-28T22:33:05.465589Z","end":"2026-05-28T22:33:05.721794Z","steps":["trace[2141470769] 'agreement among raft nodes before linearized reading' (duration: 255.985735ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:05.897529Z","caller":"traceutil/trace.go:171","msg":"trace[146292022] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"170.19409ms","start":"2026-05-28T22:33:05.727298Z","end":"2026-05-28T22:33:05.897492Z","steps":["trace[146292022] 'process raft request' (duration: 109.167709ms)","trace[146292022] 'compare' (duration: 60.864408ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:06.091983Z","caller":"traceutil/trace.go:171","msg":"trace[357734500] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"324.691331ms","start":"2026-05-28T22:33:05.767274Z","end":"2026-05-28T22:33:06.091965Z","steps":["trace[357734500] 'process raft request' (duration: 324.62712ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.092065Z","caller":"traceutil/trace.go:171","msg":"trace[957577829] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"316.019157ms","start":"2026-05-28T22:33:05.77603Z","end":"2026-05-28T22:33:06.092049Z","steps":["trace[957577829] 'process raft request' (duration: 315.966416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:06.09211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:05.767261Z","time spent":"324.782963ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:33:06.092146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:05.776018Z","time spent":"316.085458ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:06.092154Z","caller":"traceutil/trace.go:171","msg":"trace[558622581] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"317.907282ms","start":"2026-05-28T22:33:05.774186Z","end":"2026-05-28T22:33:06.092093Z","steps":["trace[558622581] 'process raft request' (duration: 317.754729ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.092149Z","caller":"traceutil/trace.go:171","msg":"trace[1570699744] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"334.835971ms","start":"2026-05-28T22:33:05.757256Z","end":"2026-05-28T22:33:06.092092Z","steps":["trace[1570699744] 'process raft request' (duration: 248.258539ms)","trace[1570699744] 'compare' (duration: 86.147144ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:06.092339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:05.774174Z","time spent":"318.061645ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T22:33:06.092444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:05.757226Z","time spent":"335.121957ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:06.690288Z","caller":"traceutil/trace.go:171","msg":"trace[1583669208] linearizableReadLoop","detail":"{readStateIndex:25541; appliedIndex:25540; }","duration":"158.907296ms","start":"2026-05-28T22:33:06.531353Z","end":"2026-05-28T22:33:06.690261Z","steps":["trace[1583669208] 'read index received' (duration: 158.60321ms)","trace[1583669208] 'applied index is now lower than readState.Index' (duration: 302.606µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:06.690387Z","caller":"traceutil/trace.go:171","msg":"trace[1900901753] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"178.22823ms","start":"2026-05-28T22:33:06.512131Z","end":"2026-05-28T22:33:06.690359Z","steps":["trace[1900901753] 'process raft request' (duration: 177.923354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:06.690565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.189241ms","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-28T22:33:06.690611Z","caller":"traceutil/trace.go:171","msg":"trace[256353574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24737; }","duration":"159.297213ms","start":"2026-05-28T22:33:06.531303Z","end":"2026-05-28T22:33:06.6906Z","steps":["trace[256353574] 'agreement among raft nodes before linearized reading' (duration: 159.066329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:06.690621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.927007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-28T22:33:06.690613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.015333ms","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-28T22:33:06.690641Z","caller":"traceutil/trace.go:171","msg":"trace[1895693424] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24737; }","duration":"122.975658ms","start":"2026-05-28T22:33:06.567658Z","end":"2026-05-28T22:33:06.690633Z","steps":["trace[1895693424] 'agreement among raft nodes before linearized reading' (duration: 122.910557ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.690661Z","caller":"traceutil/trace.go:171","msg":"trace[1765644069] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24737; }","duration":"154.138705ms","start":"2026-05-28T22:33:06.536506Z","end":"2026-05-28T22:33:06.690644Z","steps":["trace[1765644069] 'agreement among raft nodes before linearized reading' (duration: 153.933501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:06.690726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.060066ms","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-28T22:33:06.69075Z","caller":"traceutil/trace.go:171","msg":"trace[1356978686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24737; }","duration":"153.121417ms","start":"2026-05-28T22:33:06.537623Z","end":"2026-05-28T22:33:06.690744Z","steps":["trace[1356978686] 'agreement among raft nodes before linearized reading' (duration: 153.055636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:06.690868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.401422ms","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-28T22:33:06.690906Z","caller":"traceutil/trace.go:171","msg":"trace[1046967220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24737; }","duration":"153.469833ms","start":"2026-05-28T22:33:06.537427Z","end":"2026-05-28T22:33:06.690897Z","steps":["trace[1046967220] 'agreement among raft nodes before linearized reading' (duration: 153.375301ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.840874Z","caller":"traceutil/trace.go:171","msg":"trace[497576444] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"145.475822ms","start":"2026-05-28T22:33:06.695376Z","end":"2026-05-28T22:33:06.840852Z","steps":["trace[497576444] 'process raft request' (duration: 145.431211ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.840899Z","caller":"traceutil/trace.go:171","msg":"trace[693419713] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"146.149356ms","start":"2026-05-28T22:33:06.694709Z","end":"2026-05-28T22:33:06.840858Z","steps":["trace[693419713] 'process raft request' (duration: 145.89372ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.84096Z","caller":"traceutil/trace.go:171","msg":"trace[483945604] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"146.134255ms","start":"2026-05-28T22:33:06.694798Z","end":"2026-05-28T22:33:06.840932Z","steps":["trace[483945604] 'process raft request' (duration: 145.954492ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:06.841055Z","caller":"traceutil/trace.go:171","msg":"trace[106006569] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"146.186176ms","start":"2026-05-28T22:33:06.694842Z","end":"2026-05-28T22:33:06.841029Z","steps":["trace[106006569] 'process raft request' (duration: 145.941452ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:22.701391Z","caller":"traceutil/trace.go:171","msg":"trace[139215063] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"126.25479ms","start":"2026-05-28T22:33:22.575114Z","end":"2026-05-28T22:33:22.701369Z","steps":["trace[139215063] 'process raft request' (duration: 125.620588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:22.989304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.805234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:22.989399Z","caller":"traceutil/trace.go:171","msg":"trace[2084348754] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24866; }","duration":"123.936376ms","start":"2026-05-28T22:33:22.865438Z","end":"2026-05-28T22:33:22.989375Z","steps":["trace[2084348754] 'count revisions from in-memory index tree' (duration: 123.742473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:22.989449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.655837ms","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":"info","ts":"2026-05-28T22:33:22.989594Z","caller":"traceutil/trace.go:171","msg":"trace[1229345122] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:24866; }","duration":"231.865401ms","start":"2026-05-28T22:33:22.757675Z","end":"2026-05-28T22:33:22.98954Z","steps":["trace[1229345122] 'count revisions from in-memory index tree' (duration: 231.565865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:22.989846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.127956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:22.989916Z","caller":"traceutil/trace.go:171","msg":"trace[1780217065] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24866; }","duration":"205.229319ms","start":"2026-05-28T22:33:22.784672Z","end":"2026-05-28T22:33:22.989902Z","steps":["trace[1780217065] 'range keys from in-memory index tree' (duration: 205.077696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:22.989954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.484807ms","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-28T22:33:22.99006Z","caller":"traceutil/trace.go:171","msg":"trace[1645686310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24866; }","duration":"228.640999ms","start":"2026-05-28T22:33:22.76139Z","end":"2026-05-28T22:33:22.990031Z","steps":["trace[1645686310] 'range keys from in-memory index tree' (duration: 228.306734ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:23.372202Z","caller":"traceutil/trace.go:171","msg":"trace[1634993358] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"378.464514ms","start":"2026-05-28T22:33:22.993712Z","end":"2026-05-28T22:33:23.372177Z","steps":["trace[1634993358] 'process raft request' (duration: 378.335611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.372372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:22.993691Z","time spent":"378.581625ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:23.372744Z","caller":"traceutil/trace.go:171","msg":"trace[164036813] linearizableReadLoop","detail":"{readStateIndex:25676; appliedIndex:25676; }","duration":"378.765979ms","start":"2026-05-28T22:33:22.993971Z","end":"2026-05-28T22:33:23.372737Z","steps":["trace[164036813] 'read index received' (duration: 378.762299ms)","trace[164036813] 'applied index is now lower than readState.Index' (duration: 3.16µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:23.372799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.82797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:23.372818Z","caller":"traceutil/trace.go:171","msg":"trace[980275656] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24867; }","duration":"378.860361ms","start":"2026-05-28T22:33:22.993952Z","end":"2026-05-28T22:33:23.372812Z","steps":["trace[980275656] 'agreement among raft nodes before linearized reading' (duration: 378.823651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.372837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:22.993944Z","time spent":"378.889782ms","remote":"127.0.0.1:41992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-28T22:33:23.384684Z","caller":"traceutil/trace.go:171","msg":"trace[916227794] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"390.308797ms","start":"2026-05-28T22:33:22.994356Z","end":"2026-05-28T22:33:23.384664Z","steps":["trace[916227794] 'process raft request' (duration: 389.891069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.384817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:22.994348Z","time spent":"390.396428ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:23.384905Z","caller":"traceutil/trace.go:171","msg":"trace[621826436] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"390.49942ms","start":"2026-05-28T22:33:22.994401Z","end":"2026-05-28T22:33:23.3849Z","steps":["trace[621826436] 'process raft request' (duration: 389.95878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.384932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:22.994396Z","time spent":"390.522131ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:23.384979Z","caller":"traceutil/trace.go:171","msg":"trace[1696607112] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"389.821448ms","start":"2026-05-28T22:33:22.995151Z","end":"2026-05-28T22:33:23.384972Z","steps":["trace[1696607112] 'process raft request' (duration: 389.232457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.385006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:22.995144Z","time spent":"389.847559ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:23.385047Z","caller":"traceutil/trace.go:171","msg":"trace[479240696] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"389.409771ms","start":"2026-05-28T22:33:22.995632Z","end":"2026-05-28T22:33:23.385042Z","steps":["trace[479240696] 'process raft request' (duration: 388.777749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.385072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T22:33:22.995626Z","time spent":"389.434861ms","remote":"127.0.0.1:42078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T22:33:23.385111Z","caller":"traceutil/trace.go:171","msg":"trace[1454462026] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"174.682682ms","start":"2026-05-28T22:33:23.210425Z","end":"2026-05-28T22:33:23.385107Z","steps":["trace[1454462026] 'process raft request' (duration: 174.002429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.385384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.728423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:33:23.385466Z","caller":"traceutil/trace.go:171","msg":"trace[1526512480] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24872; }","duration":"157.906976ms","start":"2026-05-28T22:33:23.227544Z","end":"2026-05-28T22:33:23.385451Z","steps":["trace[1526512480] 'agreement among raft nodes before linearized reading' (duration: 157.694512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.532864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.339631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-28T22:33:23.532967Z","caller":"traceutil/trace.go:171","msg":"trace[457574474] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24872; }","duration":"135.496444ms","start":"2026-05-28T22:33:23.397446Z","end":"2026-05-28T22:33:23.532942Z","steps":["trace[457574474] 'range keys from in-memory index tree' (duration: 135.104707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:23.533018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.841357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-28T22:33:23.533108Z","caller":"traceutil/trace.go:171","msg":"trace[611258194] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24872; }","duration":"138.951569ms","start":"2026-05-28T22:33:23.394133Z","end":"2026-05-28T22:33:23.533085Z","steps":["trace[611258194] 'count revisions from in-memory index tree' (duration: 138.743165ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.028419Z","caller":"traceutil/trace.go:171","msg":"trace[1034560751] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"242.783197ms","start":"2026-05-28T22:33:23.785591Z","end":"2026-05-28T22:33:24.028374Z","steps":["trace[1034560751] 'process raft request' (duration: 242.452641ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.221985Z","caller":"traceutil/trace.go:171","msg":"trace[357035793] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"140.367656ms","start":"2026-05-28T22:33:24.081584Z","end":"2026-05-28T22:33:24.221951Z","steps":["trace[357035793] 'process raft request' (duration: 136.824619ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.308164Z","caller":"traceutil/trace.go:171","msg":"trace[1962712475] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"156.261555ms","start":"2026-05-28T22:33:24.151872Z","end":"2026-05-28T22:33:24.308134Z","steps":["trace[1962712475] 'process raft request' (duration: 156.174544ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.308252Z","caller":"traceutil/trace.go:171","msg":"trace[327819273] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"160.488814ms","start":"2026-05-28T22:33:24.147708Z","end":"2026-05-28T22:33:24.308197Z","steps":["trace[327819273] 'process raft request' (duration: 160.153408ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.308461Z","caller":"traceutil/trace.go:171","msg":"trace[1260214885] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"155.442431ms","start":"2026-05-28T22:33:24.152988Z","end":"2026-05-28T22:33:24.30843Z","steps":["trace[1260214885] 'process raft request' (duration: 155.322628ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.308558Z","caller":"traceutil/trace.go:171","msg":"trace[1273590608] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"155.595273ms","start":"2026-05-28T22:33:24.15293Z","end":"2026-05-28T22:33:24.308525Z","steps":["trace[1273590608] 'process raft request' (duration: 155.155815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:24.53669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.297653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:33:24.536984Z","caller":"traceutil/trace.go:171","msg":"trace[1948338952] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"280.262434ms","start":"2026-05-28T22:33:24.256684Z","end":"2026-05-28T22:33:24.536947Z","steps":["trace[1948338952] 'process raft request' (duration: 112.549422ms)","trace[1948338952] 'compare' (duration: 167.14011ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:24.537084Z","caller":"traceutil/trace.go:171","msg":"trace[464401639] linearizableReadLoop","detail":"{readStateIndex:25689; appliedIndex:25687; }","duration":"122.242745ms","start":"2026-05-28T22:33:24.414819Z","end":"2026-05-28T22:33:24.537062Z","steps":["trace[464401639] 'read index received' (duration: 28.051249ms)","trace[464401639] 'applied index is now lower than readState.Index' (duration: 94.190076ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:24.537185Z","caller":"traceutil/trace.go:171","msg":"trace[1659846901] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"164.092664ms","start":"2026-05-28T22:33:24.373081Z","end":"2026-05-28T22:33:24.537173Z","steps":["trace[1659846901] 'process raft request' (duration: 163.90071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:24.537356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.53493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-28T22:33:24.537402Z","caller":"traceutil/trace.go:171","msg":"trace[500661889] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:24880; }","duration":"122.627522ms","start":"2026-05-28T22:33:24.41476Z","end":"2026-05-28T22:33:24.537388Z","steps":["trace[500661889] 'agreement among raft nodes before linearized reading' (duration: 122.381517ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:24.729971Z","caller":"traceutil/trace.go:171","msg":"trace[682171677] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"126.568735ms","start":"2026-05-28T22:33:24.603364Z","end":"2026-05-28T22:33:24.729933Z","steps":["trace[682171677] 'process raft request' (duration: 110.175298ms)","trace[682171677] 'compare' (duration: 16.188054ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:24.975754Z","caller":"traceutil/trace.go:171","msg":"trace[889833468] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"171.405121ms","start":"2026-05-28T22:33:24.80431Z","end":"2026-05-28T22:33:24.975715Z","steps":["trace[889833468] 'process raft request' (duration: 171.165986ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.714618Z","caller":"traceutil/trace.go:171","msg":"trace[955098682] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"263.062849ms","start":"2026-05-28T22:33:25.451511Z","end":"2026-05-28T22:33:25.714574Z","steps":["trace[955098682] 'process raft request' (duration: 194.544356ms)","trace[955098682] 'compare' (duration: 68.032564ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:25.714821Z","caller":"traceutil/trace.go:171","msg":"trace[1891050231] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"263.270612ms","start":"2026-05-28T22:33:25.451511Z","end":"2026-05-28T22:33:25.714782Z","steps":["trace[1891050231] 'process raft request' (duration: 263.010718ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:25.714867Z","caller":"traceutil/trace.go:171","msg":"trace[1557106542] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"262.024999ms","start":"2026-05-28T22:33:25.452807Z","end":"2026-05-28T22:33:25.714832Z","steps":["trace[1557106542] 'process raft request' (duration: 261.878297ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:27.648163Z","caller":"traceutil/trace.go:171","msg":"trace[1947466424] linearizableReadLoop","detail":"{readStateIndex:25711; appliedIndex:25710; }","duration":"180.056574ms","start":"2026-05-28T22:33:27.468076Z","end":"2026-05-28T22:33:27.648132Z","steps":["trace[1947466424] 'read index received' (duration: 113.173032ms)","trace[1947466424] 'applied index is now lower than readState.Index' (duration: 66.882492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:27.648412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.302358ms","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-28T22:33:27.648411Z","caller":"traceutil/trace.go:171","msg":"trace[562969673] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"196.620896ms","start":"2026-05-28T22:33:27.451743Z","end":"2026-05-28T22:33:27.648364Z","steps":["trace[562969673] 'process raft request' (duration: 129.588152ms)","trace[562969673] 'compare' (duration: 66.512814ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:27.648455Z","caller":"traceutil/trace.go:171","msg":"trace[677726025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24901; }","duration":"180.39366ms","start":"2026-05-28T22:33:27.46805Z","end":"2026-05-28T22:33:27.648443Z","steps":["trace[677726025] 'agreement among raft nodes before linearized reading' (duration: 180.195067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:27.998429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.570155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-28T22:33:27.998681Z","caller":"traceutil/trace.go:171","msg":"trace[889986971] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"271.60996ms","start":"2026-05-28T22:33:27.727051Z","end":"2026-05-28T22:33:27.998661Z","steps":["trace[889986971] 'process raft request' (duration: 271.561089ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:27.99866Z","caller":"traceutil/trace.go:171","msg":"trace[823556167] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"274.062266ms","start":"2026-05-28T22:33:27.724569Z","end":"2026-05-28T22:33:27.998631Z","steps":["trace[823556167] 'process raft request' (duration: 121.173075ms)","trace[823556167] 'compare' (duration: 152.076916ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:27.998792Z","caller":"traceutil/trace.go:171","msg":"trace[370187697] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"273.382352ms","start":"2026-05-28T22:33:27.725374Z","end":"2026-05-28T22:33:27.998757Z","steps":["trace[370187697] 'process raft request' (duration: 273.181889ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:33.778687Z","caller":"traceutil/trace.go:171","msg":"trace[1253482295] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"108.684649ms","start":"2026-05-28T22:33:33.669966Z","end":"2026-05-28T22:33:33.778651Z","steps":["trace[1253482295] 'process raft request' (duration: 108.393313ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:39.396719Z","caller":"traceutil/trace.go:171","msg":"trace[2028991509] transaction","detail":"{read_only:false; response_revision:24997; number_of_response:1; }","duration":"153.875831ms","start":"2026-05-28T22:33:39.242815Z","end":"2026-05-28T22:33:39.39669Z","steps":["trace[2028991509] 'process raft request' (duration: 153.644127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:40.045105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.726399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:40.045272Z","caller":"traceutil/trace.go:171","msg":"trace[1313113910] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25001; }","duration":"108.965432ms","start":"2026-05-28T22:33:39.936279Z","end":"2026-05-28T22:33:40.045245Z","steps":["trace[1313113910] 'count revisions from in-memory index tree' (duration: 108.626026ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:40.267026Z","caller":"traceutil/trace.go:171","msg":"trace[703417277] linearizableReadLoop","detail":"{readStateIndex:25815; appliedIndex:25814; }","duration":"144.639876ms","start":"2026-05-28T22:33:40.122347Z","end":"2026-05-28T22:33:40.266987Z","steps":["trace[703417277] 'read index received' (duration: 140.565379ms)","trace[703417277] 'applied index is now lower than readState.Index' (duration: 4.072557ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:40.267323Z","caller":"traceutil/trace.go:171","msg":"trace[1487468097] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"172.257197ms","start":"2026-05-28T22:33:40.095029Z","end":"2026-05-28T22:33:40.267286Z","steps":["trace[1487468097] 'process raft request' (duration: 167.918125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:40.267564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.239752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-05-28T22:33:40.267561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.906079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:40.267628Z","caller":"traceutil/trace.go:171","msg":"trace[1439921907] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25003; }","duration":"211.354704ms","start":"2026-05-28T22:33:40.056254Z","end":"2026-05-28T22:33:40.267608Z","steps":["trace[1439921907] 'agreement among raft nodes before linearized reading' (duration: 210.875955ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:40.267657Z","caller":"traceutil/trace.go:171","msg":"trace[1557046326] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25003; }","duration":"155.033151ms","start":"2026-05-28T22:33:40.112602Z","end":"2026-05-28T22:33:40.267636Z","steps":["trace[1557046326] 'agreement among raft nodes before linearized reading' (duration: 154.846488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:40.268025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.643279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-28T22:33:40.268252Z","caller":"traceutil/trace.go:171","msg":"trace[730136725] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25003; }","duration":"211.872503ms","start":"2026-05-28T22:33:40.056255Z","end":"2026-05-28T22:33:40.268128Z","steps":["trace[730136725] 'agreement among raft nodes before linearized reading' (duration: 211.276442ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:40.271646Z","caller":"traceutil/trace.go:171","msg":"trace[1856781417] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"145.243188ms","start":"2026-05-28T22:33:40.12638Z","end":"2026-05-28T22:33:40.271624Z","steps":["trace[1856781417] 'process raft request' (duration: 145.047874ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:44.041441Z","caller":"traceutil/trace.go:171","msg":"trace[1319427938] linearizableReadLoop","detail":"{readStateIndex:25843; appliedIndex:25842; }","duration":"128.143935ms","start":"2026-05-28T22:33:43.913262Z","end":"2026-05-28T22:33:44.041406Z","steps":["trace[1319427938] 'read index received' (duration: 90.496416ms)","trace[1319427938] 'applied index is now lower than readState.Index' (duration: 37.645729ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T22:33:44.041598Z","caller":"traceutil/trace.go:171","msg":"trace[75124081] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"128.747277ms","start":"2026-05-28T22:33:43.912833Z","end":"2026-05-28T22:33:44.04158Z","steps":["trace[75124081] 'process raft request' (duration: 90.922114ms)","trace[75124081] 'compare' (duration: 37.385884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:44.041754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.792538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:44.041804Z","caller":"traceutil/trace.go:171","msg":"trace[600766894] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25030; }","duration":"119.892489ms","start":"2026-05-28T22:33:43.921902Z","end":"2026-05-28T22:33:44.041794Z","steps":["trace[600766894] 'agreement among raft nodes before linearized reading' (duration: 119.771477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T22:33:44.04191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.620055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-28T22:33:44.042009Z","caller":"traceutil/trace.go:171","msg":"trace[268352600] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25030; }","duration":"128.771697ms","start":"2026-05-28T22:33:43.913217Z","end":"2026-05-28T22:33:44.041988Z","steps":["trace[268352600] 'agreement among raft nodes before linearized reading' (duration: 128.448541ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.546246Z","caller":"traceutil/trace.go:171","msg":"trace[310970923] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"143.79151ms","start":"2026-05-28T22:33:50.402364Z","end":"2026-05-28T22:33:50.546155Z","steps":["trace[310970923] 'process raft request' (duration: 143.625957ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.681632Z","caller":"traceutil/trace.go:171","msg":"trace[494809050] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"277.690463ms","start":"2026-05-28T22:33:50.40391Z","end":"2026-05-28T22:33:50.6816Z","steps":["trace[494809050] 'process raft request' (duration: 277.579021ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.68167Z","caller":"traceutil/trace.go:171","msg":"trace[438740388] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"278.903426ms","start":"2026-05-28T22:33:50.402716Z","end":"2026-05-28T22:33:50.681619Z","steps":["trace[438740388] 'process raft request' (duration: 272.481915ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.681872Z","caller":"traceutil/trace.go:171","msg":"trace[2078584104] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"264.643998ms","start":"2026-05-28T22:33:50.4172Z","end":"2026-05-28T22:33:50.681844Z","steps":["trace[2078584104] 'process raft request' (duration: 264.358162ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.682148Z","caller":"traceutil/trace.go:171","msg":"trace[246905160] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"261.896887ms","start":"2026-05-28T22:33:50.420231Z","end":"2026-05-28T22:33:50.682128Z","steps":["trace[246905160] 'process raft request' (duration: 261.546049ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.682551Z","caller":"traceutil/trace.go:171","msg":"trace[31790402] linearizableReadLoop","detail":"{readStateIndex:25898; appliedIndex:25891; }","duration":"248.565045ms","start":"2026-05-28T22:33:50.433968Z","end":"2026-05-28T22:33:50.682533Z","steps":["trace[31790402] 'read index received' (duration: 112.113733ms)","trace[31790402] 'applied index is now lower than readState.Index' (duration: 136.450242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T22:33:50.682683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.698418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-28T22:33:50.682721Z","caller":"traceutil/trace.go:171","msg":"trace[752482623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25084; }","duration":"248.80852ms","start":"2026-05-28T22:33:50.433904Z","end":"2026-05-28T22:33:50.682712Z","steps":["trace[752482623] 'agreement among raft nodes before linearized reading' (duration: 248.691208ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.68292Z","caller":"traceutil/trace.go:171","msg":"trace[295367165] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"261.443128ms","start":"2026-05-28T22:33:50.421434Z","end":"2026-05-28T22:33:50.682877Z","steps":["trace[295367165] 'process raft request' (duration: 260.823576ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:33:50.683065Z","caller":"traceutil/trace.go:171","msg":"trace[723644890] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"261.660312ms","start":"2026-05-28T22:33:50.421393Z","end":"2026-05-28T22:33:50.683053Z","steps":["trace[723644890] 'process raft request' (duration: 260.663934ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T22:35:33.554846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23246} {"level":"info","ts":"2026-05-28T22:35:33.684926Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23246,"took":"125.954233ms","hash":2078603066,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":36470784,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-28T22:35:33.684999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2078603066,"revision":23246,"compact-revision":20004} {"level":"info","ts":"2026-05-28T22:35:44.337065Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069cf.wal"} {"level":"info","ts":"2026-05-28T22:40:33.562474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26142} {"level":"info","ts":"2026-05-28T22:40:33.702352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26142,"took":"135.85523ms","hash":3357792442,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":33030144,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-28T22:40:33.702422Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3357792442,"revision":26142,"compact-revision":23246} {"level":"info","ts":"2026-05-28T22:41:22.106131Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T22:41:22.111678Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-28T22:41:22.111786Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-28T22:45:33.570051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28731} {"level":"info","ts":"2026-05-28T22:45:33.708012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28731,"took":"132.703054ms","hash":2252716158,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":29904896,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-28T22:45:33.70809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2252716158,"revision":28731,"compact-revision":26142} {"level":"info","ts":"2026-05-28T22:50:33.576202Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30954} {"level":"info","ts":"2026-05-28T22:50:33.689068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30954,"took":"108.574819ms","hash":4161165525,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":30941184,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-28T22:50:33.689147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4161165525,"revision":30954,"compact-revision":28731} {"level":"info","ts":"2026-05-28T22:55:33.587073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33212} {"level":"info","ts":"2026-05-28T22:55:33.724656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33212,"took":"133.407208ms","hash":3065708270,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":31977472,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T22:55:33.724832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3065708270,"revision":33212,"compact-revision":30954} {"level":"info","ts":"2026-05-28T23:00:33.593317Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35508} {"level":"info","ts":"2026-05-28T23:00:33.73576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35508,"took":"137.411763ms","hash":3310211594,"current-db-size-bytes":61136896,"current-db-size":"61 MB","current-db-size-in-use-bytes":31551488,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T23:00:33.735883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3310211594,"revision":35508,"compact-revision":33212} {"level":"info","ts":"2026-05-28T23:02:43.26311Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f8844a9a4263041d","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-28T23:02:43.277075Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-28T23:02:43.277167Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-28T23:05:33.598409Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37801} {"level":"info","ts":"2026-05-28T23:05:33.7151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37801,"took":"113.257318ms","hash":4059085215,"current-db-size-bytes":61136896,"current-db-size":"61 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-28T23:05:33.715158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4059085215,"revision":37801,"compact-revision":35508}