{"level":"warn","ts":"2026-05-26T15:55:58.125285Z","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-26T15:55:58.125388Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.225:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.225:2380","--initial-cluster=instance=https://199.19.213.225:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.225:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.225: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-26T15:55:58.125444Z","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-26T15:55:58.125457Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-05-26T15:55:58.125473Z","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-26T15:55:58.126027Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"]} {"level":"info","ts":"2026-05-26T15:55:58.1261Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.225: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-26T15:55:58.130192Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.905353ms"} {"level":"info","ts":"2026-05-26T15:55:58.136489Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7b89c171c5fe95c","cluster-id":"5746414c29a21eae"} {"level":"info","ts":"2026-05-26T15:55:58.13656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T15:55:58.136582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 0"} {"level":"info","ts":"2026-05-26T15:55:58.1366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7b89c171c5fe95c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T15:55:58.136611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 1"} {"level":"info","ts":"2026-05-26T15:55:58.136647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"warn","ts":"2026-05-26T15:55:58.1413Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T15:55:58.142459Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T15:55:58.144329Z","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-26T15:55:58.146728Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7b89c171c5fe95c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T15:55:58.146838Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7b89c171c5fe95c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T15:55:58.147397Z","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-26T15:55:58.147448Z","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-26T15:55:58.147492Z","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-26T15:55:58.149016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"info","ts":"2026-05-26T15:55:58.149244Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","added-peer-id":"b7b89c171c5fe95c","added-peer-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-05-26T15:55:58.152996Z","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-26T15:55:58.153192Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-05-26T15:55:58.153234Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-05-26T15:55:58.153234Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7b89c171c5fe95c","initial-advertise-peer-urls":["https://199.19.213.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T15:55:58.15329Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T15:55:58.837742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T15:55:58.837791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T15:55:58.837827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgPreVoteResp from b7b89c171c5fe95c at term 1"} {"level":"info","ts":"2026-05-26T15:55:58.837838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became candidate at term 2"} {"level":"info","ts":"2026-05-26T15:55:58.837843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgVoteResp from b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-05-26T15:55:58.837851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became leader at term 2"} {"level":"info","ts":"2026-05-26T15:55:58.837857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7b89c171c5fe95c elected leader b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-05-26T15:55:58.840012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:58.841913Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:55:58.841895Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7b89c171c5fe95c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.225:2379]}","request-path":"/0/members/b7b89c171c5fe95c/attributes","cluster-id":"5746414c29a21eae","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T15:55:58.841952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T15:55:58.842359Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T15:55:58.842412Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T15:55:58.842598Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:58.843058Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:58.843096Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T15:55:58.844529Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.225:2379"} {"level":"info","ts":"2026-05-26T15:55:58.845506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T15:56:37.401386Z","caller":"traceutil/trace.go:171","msg":"trace[724909782] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"116.725057ms","start":"2026-05-26T15:56:37.28464Z","end":"2026-05-26T15:56:37.401365Z","steps":["trace[724909782] 'read index received' (duration: 1.184249ms)","trace[724909782] 'applied index is now lower than readState.Index' (duration: 115.539918ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:56:37.401591Z","caller":"traceutil/trace.go:171","msg":"trace[1975306448] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"117.610828ms","start":"2026-05-26T15:56:37.283972Z","end":"2026-05-26T15:56:37.401582Z","steps":["trace[1975306448] 'process raft request' (duration: 52.173461ms)","trace[1975306448] 'compare' (duration: 64.873304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:56:37.401896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.238409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-05-26T15:56:37.401952Z","caller":"traceutil/trace.go:171","msg":"trace[201221816] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:505; }","duration":"117.334422ms","start":"2026-05-26T15:56:37.284609Z","end":"2026-05-26T15:56:37.401943Z","steps":["trace[201221816] 'agreement among raft nodes before linearized reading' (duration: 117.152767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:37.4129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.46343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-05-26T15:56:37.413016Z","caller":"traceutil/trace.go:171","msg":"trace[30896] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:510; }","duration":"126.619294ms","start":"2026-05-26T15:56:37.28638Z","end":"2026-05-26T15:56:37.413Z","steps":["trace[30896] 'agreement among raft nodes before linearized reading' (duration: 126.406219ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:37.413305Z","caller":"traceutil/trace.go:171","msg":"trace[464970117] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"126.912631ms","start":"2026-05-26T15:56:37.286384Z","end":"2026-05-26T15:56:37.413297Z","steps":["trace[464970117] 'process raft request' (duration: 126.062741ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:37.413394Z","caller":"traceutil/trace.go:171","msg":"trace[1076781509] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"115.722482ms","start":"2026-05-26T15:56:37.29763Z","end":"2026-05-26T15:56:37.413352Z","steps":["trace[1076781509] 'process raft request' (duration: 115.117588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:37.413525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.074971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-05-26T15:56:37.413555Z","caller":"traceutil/trace.go:171","msg":"trace[684571151] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:510; }","duration":"126.133743ms","start":"2026-05-26T15:56:37.287413Z","end":"2026-05-26T15:56:37.413547Z","steps":["trace[684571151] 'agreement among raft nodes before linearized reading' (duration: 126.061951ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:37.413592Z","caller":"traceutil/trace.go:171","msg":"trace[1316718691] transaction","detail":"{read_only:false; number_of_response:1; response_revision:506; }","duration":"127.137636ms","start":"2026-05-26T15:56:37.286448Z","end":"2026-05-26T15:56:37.413585Z","steps":["trace[1316718691] 'process raft request' (duration: 126.095511ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:37.413665Z","caller":"traceutil/trace.go:171","msg":"trace[1124174958] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"126.143732ms","start":"2026-05-26T15:56:37.287517Z","end":"2026-05-26T15:56:37.413661Z","steps":["trace[1124174958] 'process raft request' (duration: 125.063367ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:37.413704Z","caller":"traceutil/trace.go:171","msg":"trace[1205022718] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"126.112961ms","start":"2026-05-26T15:56:37.287587Z","end":"2026-05-26T15:56:37.4137Z","steps":["trace[1205022718] 'process raft request' (duration: 125.045986ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:37.413738Z","caller":"traceutil/trace.go:171","msg":"trace[1270073032] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"120.735553ms","start":"2026-05-26T15:56:37.292998Z","end":"2026-05-26T15:56:37.413734Z","steps":["trace[1270073032] 'process raft request' (duration: 119.677038ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:56:43.72703Z","caller":"traceutil/trace.go:171","msg":"trace[1542016050] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"234.067197ms","start":"2026-05-26T15:56:43.492929Z","end":"2026-05-26T15:56:43.726996Z","steps":["trace[1542016050] 'process raft request' (duration: 199.101046ms)","trace[1542016050] 'compare' (duration: 34.801237ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:56:44.153994Z","caller":"traceutil/trace.go:171","msg":"trace[1290694091] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"308.050175ms","start":"2026-05-26T15:56:43.84592Z","end":"2026-05-26T15:56:44.153971Z","steps":["trace[1290694091] 'read index received' (duration: 308.025094ms)","trace[1290694091] 'applied index is now lower than readState.Index' (duration: 24.291µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:56:44.15408Z","caller":"traceutil/trace.go:171","msg":"trace[1565443814] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"360.28029ms","start":"2026-05-26T15:56:43.793776Z","end":"2026-05-26T15:56:44.154056Z","steps":["trace[1565443814] 'process raft request' (duration: 360.060815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:44.154188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.26341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T15:56:44.154225Z","caller":"traceutil/trace.go:171","msg":"trace[1002255729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:596; }","duration":"308.344292ms","start":"2026-05-26T15:56:43.845869Z","end":"2026-05-26T15:56:44.154214Z","steps":["trace[1002255729] 'agreement among raft nodes before linearized reading' (duration: 308.195988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:56:44.154256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:43.84585Z","time spent":"308.399004ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T15:56:44.154587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:56:43.793741Z","time spent":"360.387713ms","remote":"127.0.0.1:41324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T15:56:44.255326Z","caller":"traceutil/trace.go:171","msg":"trace[1746481945] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"133.208463ms","start":"2026-05-26T15:56:44.122093Z","end":"2026-05-26T15:56:44.255302Z","steps":["trace[1746481945] 'process raft request' (duration: 132.661389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:12.05589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.913451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.225\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-26T15:58:12.055977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.335221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-26T15:58:12.056009Z","caller":"traceutil/trace.go:171","msg":"trace[282662968] range","detail":"{range_begin:/registry/masterleases/199.19.213.225; range_end:; response_count:1; response_revision:1803; }","duration":"203.070926ms","start":"2026-05-26T15:58:11.852912Z","end":"2026-05-26T15:58:12.055983Z","steps":["trace[282662968] 'range keys from in-memory index tree' (duration: 202.767177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:12.056062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.6151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d9e4525-17bf-4aa6-aa7b-ec780c60b76d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-26T15:58:12.056058Z","caller":"traceutil/trace.go:171","msg":"trace[1405436939] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1803; }","duration":"225.448905ms","start":"2026-05-26T15:58:11.830587Z","end":"2026-05-26T15:58:12.056036Z","steps":["trace[1405436939] 'range keys from in-memory index tree' (duration: 225.190738ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:58:12.056095Z","caller":"traceutil/trace.go:171","msg":"trace[380626667] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d9e4525-17bf-4aa6-aa7b-ec780c60b76d; range_end:; response_count:1; response_revision:1803; }","duration":"111.688022ms","start":"2026-05-26T15:58:11.944397Z","end":"2026-05-26T15:58:12.056085Z","steps":["trace[380626667] 'range keys from in-memory index tree' (duration: 111.504408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:12.480431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.830791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:58:12.480596Z","caller":"traceutil/trace.go:171","msg":"trace[1158374675] linearizableReadLoop","detail":"{readStateIndex:1877; appliedIndex:1876; }","duration":"256.289107ms","start":"2026-05-26T15:58:12.224285Z","end":"2026-05-26T15:58:12.480574Z","steps":["trace[1158374675] 'read index received' (duration: 29.11µs)","trace[1158374675] 'applied index is now lower than readState.Index' (duration: 256.258047ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T15:58:12.480637Z","caller":"traceutil/trace.go:171","msg":"trace[1376770804] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"284.539608ms","start":"2026-05-26T15:58:12.196054Z","end":"2026-05-26T15:58:12.480594Z","steps":["trace[1376770804] 'process raft request' (duration: 27.081052ms)","trace[1376770804] 'compare' (duration: 256.399121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:58:12.480766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.734271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:58:12.4808Z","caller":"traceutil/trace.go:171","msg":"trace[836125917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1806; }","duration":"113.801102ms","start":"2026-05-26T15:58:12.366988Z","end":"2026-05-26T15:58:12.480789Z","steps":["trace[836125917] 'agreement among raft nodes before linearized reading' (duration: 113.71035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:58:12.480788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.485172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d9e4525-17bf-4aa6-aa7b-ec780c60b76d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-26T15:58:12.480851Z","caller":"traceutil/trace.go:171","msg":"trace[1531785907] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d9e4525-17bf-4aa6-aa7b-ec780c60b76d; range_end:; response_count:1; response_revision:1806; }","duration":"256.602615ms","start":"2026-05-26T15:58:12.224233Z","end":"2026-05-26T15:58:12.480836Z","steps":["trace[1531785907] 'agreement among raft nodes before linearized reading' (duration: 256.421971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:04.689279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.332187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-26T15:59:04.689353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.052076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T15:59:04.689433Z","caller":"traceutil/trace.go:171","msg":"trace[322164159] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"240.543612ms","start":"2026-05-26T15:59:04.448856Z","end":"2026-05-26T15:59:04.6894Z","steps":["trace[322164159] 'range keys from in-memory index tree' (duration: 240.106532ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:59:04.68949Z","caller":"traceutil/trace.go:171","msg":"trace[1633266738] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2143; }","duration":"258.244671ms","start":"2026-05-26T15:59:04.431209Z","end":"2026-05-26T15:59:04.689454Z","steps":["trace[1633266738] 'count revisions from in-memory index tree' (duration: 257.961963ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T15:59:04.960472Z","caller":"traceutil/trace.go:171","msg":"trace[482894558] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"127.058911ms","start":"2026-05-26T15:59:04.83337Z","end":"2026-05-26T15:59:04.960429Z","steps":["trace[482894558] 'process raft request' (duration: 126.752634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T15:59:05.367559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.013672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T15:59:05.367645Z","caller":"traceutil/trace.go:171","msg":"trace[790125666] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"402.442864ms","start":"2026-05-26T15:59:04.965189Z","end":"2026-05-26T15:59:05.367632Z","steps":["trace[790125666] 'process raft request' (duration: 135.490934ms)","trace[790125666] 'compare' (duration: 266.581842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T15:59:05.367705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T15:59:04.965178Z","time spent":"402.499447ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:00:32.176684Z","caller":"traceutil/trace.go:171","msg":"trace[834954545] linearizableReadLoop","detail":"{readStateIndex:2918; appliedIndex:2917; }","duration":"106.711849ms","start":"2026-05-26T16:00:32.069943Z","end":"2026-05-26T16:00:32.176655Z","steps":["trace[834954545] 'read index received' (duration: 106.531375ms)","trace[834954545] 'applied index is now lower than readState.Index' (duration: 179.224µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:00:32.176791Z","caller":"traceutil/trace.go:171","msg":"trace[1441549418] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"107.998521ms","start":"2026-05-26T16:00:32.068769Z","end":"2026-05-26T16:00:32.176767Z","steps":["trace[1441549418] 'process raft request' (duration: 107.710914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:00:32.176922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.954125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T16:00:32.176981Z","caller":"traceutil/trace.go:171","msg":"trace[915096684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2810; }","duration":"107.042296ms","start":"2026-05-26T16:00:32.069923Z","end":"2026-05-26T16:00:32.176966Z","steps":["trace[915096684] 'agreement among raft nodes before linearized reading' (duration: 106.856012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:00:32.455555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.347152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:00:32.455688Z","caller":"traceutil/trace.go:171","msg":"trace[1574647520] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2811; }","duration":"170.535638ms","start":"2026-05-26T16:00:32.285129Z","end":"2026-05-26T16:00:32.455665Z","steps":["trace[1574647520] 'count revisions from in-memory index tree' (duration: 170.22257ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:00:32.456415Z","caller":"traceutil/trace.go:171","msg":"trace[2019689013] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"143.649305ms","start":"2026-05-26T16:00:32.312738Z","end":"2026-05-26T16:00:32.456388Z","steps":["trace[2019689013] 'process raft request' (duration: 53.98602ms)","trace[2019689013] 'compare' (duration: 88.976978ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:02:57.654828Z","caller":"traceutil/trace.go:171","msg":"trace[1302660378] transaction","detail":"{read_only:false; response_revision:3780; number_of_response:1; }","duration":"153.122843ms","start":"2026-05-26T16:02:57.501671Z","end":"2026-05-26T16:02:57.654793Z","steps":["trace[1302660378] 'process raft request' (duration: 137.871853ms)","trace[1302660378] 'compare' (duration: 14.783699ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:03.031371Z","caller":"traceutil/trace.go:171","msg":"trace[1617013459] linearizableReadLoop","detail":"{readStateIndex:3952; appliedIndex:3951; }","duration":"104.493554ms","start":"2026-05-26T16:03:02.926852Z","end":"2026-05-26T16:03:03.031345Z","steps":["trace[1617013459] 'read index received' (duration: 104.396041ms)","trace[1617013459] 'applied index is now lower than readState.Index' (duration: 96.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:03:03.031423Z","caller":"traceutil/trace.go:171","msg":"trace[2069894174] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"131.503819ms","start":"2026-05-26T16:03:02.899893Z","end":"2026-05-26T16:03:03.031396Z","steps":["trace[2069894174] 'process raft request' (duration: 131.313074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:03:03.031563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.690398ms","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-26T16:03:03.0316Z","caller":"traceutil/trace.go:171","msg":"trace[833035408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3807; }","duration":"104.78442ms","start":"2026-05-26T16:03:02.926807Z","end":"2026-05-26T16:03:03.031591Z","steps":["trace[833035408] 'agreement among raft nodes before linearized reading' (duration: 104.635167ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:18.080709Z","caller":"traceutil/trace.go:171","msg":"trace[517228232] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"106.420412ms","start":"2026-05-26T16:04:17.974259Z","end":"2026-05-26T16:04:18.080679Z","steps":["trace[517228232] 'process raft request' (duration: 100.937729ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:26.124118Z","caller":"traceutil/trace.go:171","msg":"trace[1282373338] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"129.268888ms","start":"2026-05-26T16:04:25.99482Z","end":"2026-05-26T16:04:26.124089Z","steps":["trace[1282373338] 'process raft request' (duration: 129.104044ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:26.2913Z","caller":"traceutil/trace.go:171","msg":"trace[1111759919] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"296.016573ms","start":"2026-05-26T16:04:25.99524Z","end":"2026-05-26T16:04:26.291257Z","steps":["trace[1111759919] 'process raft request' (duration: 295.538792ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:39.542882Z","caller":"traceutil/trace.go:171","msg":"trace[1184049686] linearizableReadLoop","detail":"{readStateIndex:5428; appliedIndex:5425; }","duration":"178.348608ms","start":"2026-05-26T16:04:39.36452Z","end":"2026-05-26T16:04:39.542868Z","steps":["trace[1184049686] 'read index received' (duration: 14.287947ms)","trace[1184049686] 'applied index is now lower than readState.Index' (duration: 164.060131ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:04:39.542948Z","caller":"traceutil/trace.go:171","msg":"trace[98770918] transaction","detail":"{read_only:false; response_revision:5232; number_of_response:1; }","duration":"192.300587ms","start":"2026-05-26T16:04:39.350641Z","end":"2026-05-26T16:04:39.542941Z","steps":["trace[98770918] 'process raft request' (duration: 192.199345ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:39.542941Z","caller":"traceutil/trace.go:171","msg":"trace[717220088] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"194.153582ms","start":"2026-05-26T16:04:39.348762Z","end":"2026-05-26T16:04:39.542916Z","steps":["trace[717220088] 'process raft request' (duration: 193.974368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:04:39.543114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.581384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:04:39.543173Z","caller":"traceutil/trace.go:171","msg":"trace[1615233728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5232; }","duration":"178.677466ms","start":"2026-05-26T16:04:39.364483Z","end":"2026-05-26T16:04:39.54316Z","steps":["trace[1615233728] 'agreement among raft nodes before linearized reading' (duration: 178.563684ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:39.545662Z","caller":"traceutil/trace.go:171","msg":"trace[491137922] transaction","detail":"{read_only:false; response_revision:5233; number_of_response:1; }","duration":"105.795788ms","start":"2026-05-26T16:04:39.439853Z","end":"2026-05-26T16:04:39.545649Z","steps":["trace[491137922] 'process raft request' (duration: 105.557022ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:47.340543Z","caller":"traceutil/trace.go:171","msg":"trace[642732523] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"102.804235ms","start":"2026-05-26T16:04:47.237706Z","end":"2026-05-26T16:04:47.34051Z","steps":["trace[642732523] 'process raft request' (duration: 99.222069ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:04:49.387458Z","caller":"traceutil/trace.go:171","msg":"trace[1667481815] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"118.132167ms","start":"2026-05-26T16:04:49.269286Z","end":"2026-05-26T16:04:49.387418Z","steps":["trace[1667481815] 'process raft request' (duration: 110.599924ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:05:58.895071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2956} {"level":"info","ts":"2026-05-26T16:05:58.959486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2956,"took":"63.214965ms","hash":2309763429,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":33959936,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T16:05:58.959567Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2309763429,"revision":2956,"compact-revision":-1} {"level":"info","ts":"2026-05-26T16:10:50.99285Z","caller":"traceutil/trace.go:171","msg":"trace[1536732125] transaction","detail":"{read_only:false; response_revision:8209; number_of_response:1; }","duration":"124.486101ms","start":"2026-05-26T16:10:50.868335Z","end":"2026-05-26T16:10:50.992821Z","steps":["trace[1536732125] 'process raft request' (duration: 123.932717ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:10:58.903331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6057} {"level":"info","ts":"2026-05-26T16:10:58.991219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6057,"took":"86.195091ms","hash":2572792487,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":23318528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-26T16:10:58.991349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2572792487,"revision":6057,"compact-revision":2956} {"level":"info","ts":"2026-05-26T16:11:02.12656Z","caller":"traceutil/trace.go:171","msg":"trace[664074453] linearizableReadLoop","detail":"{readStateIndex:8563; appliedIndex:8562; }","duration":"136.154313ms","start":"2026-05-26T16:11:01.99038Z","end":"2026-05-26T16:11:02.126534Z","steps":["trace[664074453] 'read index received' (duration: 130.447415ms)","trace[664074453] 'applied index is now lower than readState.Index' (duration: 5.705828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:11:02.126773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.387329ms","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-26T16:11:02.126818Z","caller":"traceutil/trace.go:171","msg":"trace[1738233745] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8271; }","duration":"136.480242ms","start":"2026-05-26T16:11:01.990324Z","end":"2026-05-26T16:11:02.126804Z","steps":["trace[1738233745] 'agreement among raft nodes before linearized reading' (duration: 136.305037ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:11:02.126818Z","caller":"traceutil/trace.go:171","msg":"trace[1498102288] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"224.732753ms","start":"2026-05-26T16:11:01.902022Z","end":"2026-05-26T16:11:02.126755Z","steps":["trace[1498102288] 'process raft request' (duration: 218.791928ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:11:02.13676Z","caller":"traceutil/trace.go:171","msg":"trace[1853570730] transaction","detail":"{read_only:false; response_revision:8272; number_of_response:1; }","duration":"140.319445ms","start":"2026-05-26T16:11:01.996412Z","end":"2026-05-26T16:11:02.136731Z","steps":["trace[1853570730] 'process raft request' (duration: 140.153251ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:11:31.09431Z","caller":"traceutil/trace.go:171","msg":"trace[658112600] linearizableReadLoop","detail":"{readStateIndex:8777; appliedIndex:8776; }","duration":"136.023791ms","start":"2026-05-26T16:11:30.958262Z","end":"2026-05-26T16:11:31.094286Z","steps":["trace[658112600] 'read index received' (duration: 135.780755ms)","trace[658112600] 'applied index is now lower than readState.Index' (duration: 242.106µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:11:31.094432Z","caller":"traceutil/trace.go:171","msg":"trace[1598948438] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"259.437935ms","start":"2026-05-26T16:11:30.834965Z","end":"2026-05-26T16:11:31.094403Z","steps":["trace[1598948438] 'process raft request' (duration: 259.170019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:11:31.094686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.244766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6lfhl\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-26T16:11:31.094754Z","caller":"traceutil/trace.go:171","msg":"trace[507416572] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6lfhl; range_end:; response_count:1; response_revision:8478; }","duration":"136.527653ms","start":"2026-05-26T16:11:30.95821Z","end":"2026-05-26T16:11:31.094738Z","steps":["trace[507416572] 'agreement among raft nodes before linearized reading' (duration: 136.181775ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:11:34.160951Z","caller":"traceutil/trace.go:171","msg":"trace[281534696] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"135.590381ms","start":"2026-05-26T16:11:34.025332Z","end":"2026-05-26T16:11:34.160923Z","steps":["trace[281534696] 'process raft request' (duration: 135.466078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:11:43.840342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.520715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T16:11:43.84048Z","caller":"traceutil/trace.go:171","msg":"trace[100651191] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"158.321692ms","start":"2026-05-26T16:11:43.682135Z","end":"2026-05-26T16:11:43.840457Z","steps":["trace[100651191] 'process raft request' (duration: 44.556381ms)","trace[100651191] 'compare' (duration: 113.410083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:12:11.133689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.924104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-89c7cc858-sd584.18b329707bac91fa\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-05-26T16:12:11.133787Z","caller":"traceutil/trace.go:171","msg":"trace[1994700333] range","detail":"{range_begin:/registry/events/openstack/barbican-api-89c7cc858-sd584.18b329707bac91fa; range_end:; response_count:1; response_revision:8886; }","duration":"302.060018ms","start":"2026-05-26T16:12:10.83171Z","end":"2026-05-26T16:12:11.13377Z","steps":["trace[1994700333] 'range keys from in-memory index tree' (duration: 301.782421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:12:11.133812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:12:10.831682Z","time spent":"302.12337ms","remote":"127.0.0.1:41226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1155,"request content":"key:\"/registry/events/openstack/barbican-api-89c7cc858-sd584.18b329707bac91fa\" "} {"level":"info","ts":"2026-05-26T16:12:29.123627Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002466.wal"} {"level":"info","ts":"2026-05-26T16:13:43.980402Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7b89c171c5fe95c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T16:13:43.986075Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-26T16:13:43.986199Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-05-26T16:15:14.683907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.614556ms","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-26T16:15:14.68401Z","caller":"traceutil/trace.go:171","msg":"trace[1988843503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10377; }","duration":"106.77684ms","start":"2026-05-26T16:15:14.57721Z","end":"2026-05-26T16:15:14.683986Z","steps":["trace[1988843503] 'range keys from in-memory index tree' (duration: 106.330499ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:15:14.883458Z","caller":"traceutil/trace.go:171","msg":"trace[1836764611] transaction","detail":"{read_only:false; response_revision:10380; number_of_response:1; }","duration":"105.080747ms","start":"2026-05-26T16:15:14.778345Z","end":"2026-05-26T16:15:14.883426Z","steps":["trace[1836764611] 'process raft request' (duration: 104.919514ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:15:15.132937Z","caller":"traceutil/trace.go:171","msg":"trace[882283270] linearizableReadLoop","detail":"{readStateIndex:10741; appliedIndex:10741; }","duration":"215.854774ms","start":"2026-05-26T16:15:14.917011Z","end":"2026-05-26T16:15:15.132866Z","steps":["trace[882283270] 'read index received' (duration: 215.834773ms)","trace[882283270] 'applied index is now lower than readState.Index' (duration: 16.941µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:15:15.13313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.08856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T16:15:15.133162Z","caller":"traceutil/trace.go:171","msg":"trace[769596411] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:10381; }","duration":"216.179403ms","start":"2026-05-26T16:15:14.916971Z","end":"2026-05-26T16:15:15.13315Z","steps":["trace[769596411] 'agreement among raft nodes before linearized reading' (duration: 216.07847ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:15:15.13294Z","caller":"traceutil/trace.go:171","msg":"trace[337803403] transaction","detail":"{read_only:false; response_revision:10381; number_of_response:1; }","duration":"246.361283ms","start":"2026-05-26T16:15:14.886525Z","end":"2026-05-26T16:15:15.132887Z","steps":["trace[337803403] 'process raft request' (duration: 246.053376ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:15:15.142716Z","caller":"traceutil/trace.go:171","msg":"trace[1090406152] transaction","detail":"{read_only:false; response_revision:10382; number_of_response:1; }","duration":"107.180068ms","start":"2026-05-26T16:15:15.035511Z","end":"2026-05-26T16:15:15.142691Z","steps":["trace[1090406152] 'process raft request' (duration: 106.938163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:15:15.142715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.576857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T16:15:15.142796Z","caller":"traceutil/trace.go:171","msg":"trace[1386449010] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:10382; }","duration":"135.70518ms","start":"2026-05-26T16:15:15.007071Z","end":"2026-05-26T16:15:15.142776Z","steps":["trace[1386449010] 'agreement among raft nodes before linearized reading' (duration: 135.534626ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:15:58.908756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8250} {"level":"info","ts":"2026-05-26T16:15:58.987541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8250,"took":"76.622148ms","hash":1128541466,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":25604096,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-26T16:15:58.987611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1128541466,"revision":8250,"compact-revision":6057} {"level":"info","ts":"2026-05-26T16:16:37.525927Z","caller":"traceutil/trace.go:171","msg":"trace[2037250592] transaction","detail":"{read_only:false; response_revision:11161; number_of_response:1; }","duration":"127.181672ms","start":"2026-05-26T16:16:37.39871Z","end":"2026-05-26T16:16:37.525892Z","steps":["trace[2037250592] 'process raft request' (duration: 126.907265ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:20:58.915287Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10759} {"level":"info","ts":"2026-05-26T16:20:58.992904Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10759,"took":"75.725796ms","hash":1394197552,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":27922432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-26T16:20:58.992962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1394197552,"revision":10759,"compact-revision":8250} {"level":"info","ts":"2026-05-26T16:22:21.847239Z","caller":"traceutil/trace.go:171","msg":"trace[211686642] transaction","detail":"{read_only:false; response_revision:13521; number_of_response:1; }","duration":"105.881597ms","start":"2026-05-26T16:22:21.741338Z","end":"2026-05-26T16:22:21.84722Z","steps":["trace[211686642] 'process raft request' (duration: 105.761104ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:22:21.847729Z","caller":"traceutil/trace.go:171","msg":"trace[611721460] linearizableReadLoop","detail":"{readStateIndex:13982; appliedIndex:13982; }","duration":"104.921144ms","start":"2026-05-26T16:22:21.7428Z","end":"2026-05-26T16:22:21.847721Z","steps":["trace[611721460] 'read index received' (duration: 104.917534ms)","trace[611721460] 'applied index is now lower than readState.Index' (duration: 3.03µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:22:21.847831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.016866ms","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-26T16:22:21.84786Z","caller":"traceutil/trace.go:171","msg":"trace[1717101502] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13521; }","duration":"105.069208ms","start":"2026-05-26T16:22:21.74278Z","end":"2026-05-26T16:22:21.84785Z","steps":["trace[1717101502] 'agreement among raft nodes before linearized reading' (duration: 104.974166ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:22:24.897398Z","caller":"traceutil/trace.go:171","msg":"trace[429007329] transaction","detail":"{read_only:false; response_revision:13538; number_of_response:1; }","duration":"143.95054ms","start":"2026-05-26T16:22:24.753422Z","end":"2026-05-26T16:22:24.897373Z","steps":["trace[429007329] 'process raft request' (duration: 143.801106ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:23:00.764748Z","caller":"traceutil/trace.go:171","msg":"trace[1269860587] transaction","detail":"{read_only:false; response_revision:13743; number_of_response:1; }","duration":"134.149563ms","start":"2026-05-26T16:23:00.630577Z","end":"2026-05-26T16:23:00.764727Z","steps":["trace[1269860587] 'process raft request' (duration: 134.003219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:22.318872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.404141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee41de7e-1ef4-4420-a921-28443908a924\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-26T16:23:22.318983Z","caller":"traceutil/trace.go:171","msg":"trace[978342713] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee41de7e-1ef4-4420-a921-28443908a924; range_end:; response_count:1; response_revision:14049; }","duration":"210.549625ms","start":"2026-05-26T16:23:22.108406Z","end":"2026-05-26T16:23:22.318956Z","steps":["trace[978342713] 'range keys from in-memory index tree' (duration: 210.205436ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:23:22.318987Z","caller":"traceutil/trace.go:171","msg":"trace[458561435] linearizableReadLoop","detail":"{readStateIndex:14534; appliedIndex:14533; }","duration":"155.274644ms","start":"2026-05-26T16:23:22.163689Z","end":"2026-05-26T16:23:22.318964Z","steps":["trace[458561435] 'read index received' (duration: 111.101863ms)","trace[458561435] 'applied index is now lower than readState.Index' (duration: 44.172001ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:23:22.319059Z","caller":"traceutil/trace.go:171","msg":"trace[503409366] transaction","detail":"{read_only:false; response_revision:14050; number_of_response:1; }","duration":"181.140072ms","start":"2026-05-26T16:23:22.13791Z","end":"2026-05-26T16:23:22.31905Z","steps":["trace[503409366] 'process raft request' (duration: 136.870258ms)","trace[503409366] 'compare' (duration: 44.026398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:23:22.319237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.52686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-26T16:23:22.319429Z","caller":"traceutil/trace.go:171","msg":"trace[1389431540] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14050; }","duration":"155.785987ms","start":"2026-05-26T16:23:22.163634Z","end":"2026-05-26T16:23:22.31942Z","steps":["trace[1389431540] 'agreement among raft nodes before linearized reading' (duration: 155.49242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:23:22.334355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.640917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T16:23:22.334425Z","caller":"traceutil/trace.go:171","msg":"trace[845538908] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:14051; }","duration":"137.75637ms","start":"2026-05-26T16:23:22.196653Z","end":"2026-05-26T16:23:22.334409Z","steps":["trace[845538908] 'agreement among raft nodes before linearized reading' (duration: 137.617876ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:24:28.390083Z","caller":"traceutil/trace.go:171","msg":"trace[1767722049] transaction","detail":"{read_only:false; response_revision:14774; number_of_response:1; }","duration":"107.726691ms","start":"2026-05-26T16:24:28.28233Z","end":"2026-05-26T16:24:28.390057Z","steps":["trace[1767722049] 'process raft request' (duration: 107.547787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:24:33.884169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.595299ms","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-26T16:24:33.884268Z","caller":"traceutil/trace.go:171","msg":"trace[287110541] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14804; }","duration":"105.753913ms","start":"2026-05-26T16:24:33.778493Z","end":"2026-05-26T16:24:33.884246Z","steps":["trace[287110541] 'range keys from in-memory index tree' (duration: 105.379094ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:24:34.004393Z","caller":"traceutil/trace.go:171","msg":"trace[271476998] linearizableReadLoop","detail":"{readStateIndex:15310; appliedIndex:15309; }","duration":"111.79268ms","start":"2026-05-26T16:24:33.892576Z","end":"2026-05-26T16:24:34.004368Z","steps":["trace[271476998] 'read index received' (duration: 111.564604ms)","trace[271476998] 'applied index is now lower than readState.Index' (duration: 227.246µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:24:34.004434Z","caller":"traceutil/trace.go:171","msg":"trace[2014360791] transaction","detail":"{read_only:false; response_revision:14805; number_of_response:1; }","duration":"115.51567ms","start":"2026-05-26T16:24:33.888896Z","end":"2026-05-26T16:24:34.004411Z","steps":["trace[2014360791] 'process raft request' (duration: 115.328475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:24:34.004622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.707209ms","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:5"} {"level":"info","ts":"2026-05-26T16:24:34.004655Z","caller":"traceutil/trace.go:171","msg":"trace[183852773] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14805; }","duration":"109.759501ms","start":"2026-05-26T16:24:33.894887Z","end":"2026-05-26T16:24:34.004646Z","steps":["trace[183852773] 'agreement among raft nodes before linearized reading' (duration: 109.687169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:24:34.006224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.620994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737488"} {"level":"info","ts":"2026-05-26T16:24:34.006457Z","caller":"traceutil/trace.go:171","msg":"trace[105202759] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14805; }","duration":"113.904621ms","start":"2026-05-26T16:24:33.892528Z","end":"2026-05-26T16:24:34.006432Z","steps":["trace[105202759] 'agreement among raft nodes before linearized reading' (duration: 111.979424ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:24:34.880986Z","caller":"traceutil/trace.go:171","msg":"trace[627193015] transaction","detail":"{read_only:false; response_revision:14809; number_of_response:1; }","duration":"104.264497ms","start":"2026-05-26T16:24:34.776689Z","end":"2026-05-26T16:24:34.880954Z","steps":["trace[627193015] 'process raft request' (duration: 104.087363ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:24:35.308869Z","caller":"traceutil/trace.go:171","msg":"trace[807276239] transaction","detail":"{read_only:false; response_revision:14810; number_of_response:1; }","duration":"108.012589ms","start":"2026-05-26T16:24:35.200835Z","end":"2026-05-26T16:24:35.308847Z","steps":["trace[807276239] 'process raft request' (duration: 107.423425ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:25:58.921944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12941} {"level":"info","ts":"2026-05-26T16:25:58.999556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12941,"took":"74.566598ms","hash":701290886,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":30748672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T16:25:58.999633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":701290886,"revision":12941,"compact-revision":10759} {"level":"info","ts":"2026-05-26T16:30:25.373074Z","caller":"traceutil/trace.go:171","msg":"trace[571217858] transaction","detail":"{read_only:false; response_revision:17407; number_of_response:1; }","duration":"149.868054ms","start":"2026-05-26T16:30:25.223184Z","end":"2026-05-26T16:30:25.373053Z","steps":["trace[571217858] 'process raft request' (duration: 149.68447ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:30:31.586748Z","caller":"traceutil/trace.go:171","msg":"trace[1545733324] linearizableReadLoop","detail":"{readStateIndex:18032; appliedIndex:18031; }","duration":"114.945039ms","start":"2026-05-26T16:30:31.471778Z","end":"2026-05-26T16:30:31.586723Z","steps":["trace[1545733324] 'read index received' (duration: 114.758933ms)","trace[1545733324] 'applied index is now lower than readState.Index' (duration: 185.206µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:30:31.586848Z","caller":"traceutil/trace.go:171","msg":"trace[1359566554] transaction","detail":"{read_only:false; response_revision:17440; number_of_response:1; }","duration":"175.219081ms","start":"2026-05-26T16:30:31.411592Z","end":"2026-05-26T16:30:31.586811Z","steps":["trace[1359566554] 'process raft request' (duration: 174.997285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:30:31.586948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.152404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9rtd6\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-05-26T16:30:31.586989Z","caller":"traceutil/trace.go:171","msg":"trace[1640455424] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9rtd6; range_end:; response_count:1; response_revision:17440; }","duration":"115.242006ms","start":"2026-05-26T16:30:31.471736Z","end":"2026-05-26T16:30:31.586978Z","steps":["trace[1640455424] 'agreement among raft nodes before linearized reading' (duration: 115.085052ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:30:32.226981Z","caller":"traceutil/trace.go:171","msg":"trace[140678826] transaction","detail":"{read_only:false; response_revision:17443; number_of_response:1; }","duration":"442.115524ms","start":"2026-05-26T16:30:31.784844Z","end":"2026-05-26T16:30:32.226959Z","steps":["trace[140678826] 'process raft request' (duration: 441.970381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:30:32.227107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:30:31.784816Z","time spent":"442.227217ms","remote":"127.0.0.1:41378","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-26T16:30:32.248722Z","caller":"traceutil/trace.go:171","msg":"trace[1868488711] linearizableReadLoop","detail":"{readStateIndex:18036; appliedIndex:18034; }","duration":"294.707959ms","start":"2026-05-26T16:30:31.953989Z","end":"2026-05-26T16:30:32.248697Z","steps":["trace[1868488711] 'read index received' (duration: 272.847668ms)","trace[1868488711] 'applied index is now lower than readState.Index' (duration: 21.859151ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:30:32.248874Z","caller":"traceutil/trace.go:171","msg":"trace[491897471] transaction","detail":"{read_only:false; response_revision:17444; number_of_response:1; }","duration":"326.627304ms","start":"2026-05-26T16:30:31.922231Z","end":"2026-05-26T16:30:32.248859Z","steps":["trace[491897471] 'process raft request' (duration: 326.344087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:30:32.249178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:30:31.922204Z","time spent":"326.92241ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:30:32.249608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.61469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-qr7fs\" ","response":"range_response_count:1 size:9800"} {"level":"warn","ts":"2026-05-26T16:30:32.249459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.498495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-xr5fp\" ","response":"range_response_count:1 size:20873"} {"level":"info","ts":"2026-05-26T16:30:32.24966Z","caller":"traceutil/trace.go:171","msg":"trace[1821235248] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-qr7fs; range_end:; response_count:1; response_revision:17444; }","duration":"215.681721ms","start":"2026-05-26T16:30:32.033959Z","end":"2026-05-26T16:30:32.249641Z","steps":["trace[1821235248] 'agreement among raft nodes before linearized reading' (duration: 215.559318ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:30:32.249722Z","caller":"traceutil/trace.go:171","msg":"trace[1258654262] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-xr5fp; range_end:; response_count:1; response_revision:17444; }","duration":"224.807633ms","start":"2026-05-26T16:30:32.02489Z","end":"2026-05-26T16:30:32.249698Z","steps":["trace[1258654262] 'agreement among raft nodes before linearized reading' (duration: 224.387913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:30:32.249034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.025056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.225\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-26T16:30:32.249856Z","caller":"traceutil/trace.go:171","msg":"trace[1239828243] range","detail":"{range_begin:/registry/masterleases/199.19.213.225; range_end:; response_count:1; response_revision:17444; }","duration":"295.895226ms","start":"2026-05-26T16:30:31.95395Z","end":"2026-05-26T16:30:32.249845Z","steps":["trace[1239828243] 'agreement among raft nodes before linearized reading' (duration: 294.952814ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:30:32.488152Z","caller":"traceutil/trace.go:171","msg":"trace[1182054654] linearizableReadLoop","detail":"{readStateIndex:18037; appliedIndex:18036; }","duration":"233.626937ms","start":"2026-05-26T16:30:32.254501Z","end":"2026-05-26T16:30:32.488128Z","steps":["trace[1182054654] 'read index received' (duration: 191.619228ms)","trace[1182054654] 'applied index is now lower than readState.Index' (duration: 42.006269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:30:32.489573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.040882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946916"} {"level":"info","ts":"2026-05-26T16:30:32.489667Z","caller":"traceutil/trace.go:171","msg":"trace[371170558] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17444; }","duration":"235.172084ms","start":"2026-05-26T16:30:32.254473Z","end":"2026-05-26T16:30:32.489645Z","steps":["trace[371170558] 'agreement among raft nodes before linearized reading' (duration: 233.76876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:30:32.515226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.794135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:30:32.51535Z","caller":"traceutil/trace.go:171","msg":"trace[981187476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17445; }","duration":"147.914668ms","start":"2026-05-26T16:30:32.367366Z","end":"2026-05-26T16:30:32.515281Z","steps":["trace[981187476] 'agreement among raft nodes before linearized reading' (duration: 147.744274ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:30:32.515408Z","caller":"traceutil/trace.go:171","msg":"trace[985967008] transaction","detail":"{read_only:false; response_revision:17445; number_of_response:1; }","duration":"247.159275ms","start":"2026-05-26T16:30:32.268213Z","end":"2026-05-26T16:30:32.515372Z","steps":["trace[985967008] 'process raft request' (duration: 246.741115ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:30:58.930032Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15335} {"level":"info","ts":"2026-05-26T16:30:59.026142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15335,"took":"92.946994ms","hash":1374263204,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":32571392,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-26T16:30:59.026213Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1374263204,"revision":15335,"compact-revision":12941} {"level":"info","ts":"2026-05-26T16:34:25.161636Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7b89c171c5fe95c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T16:34:25.166139Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-26T16:34:25.16624Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-26T16:35:59.131502Z","caller":"traceutil/trace.go:171","msg":"trace[2061052449] compact","detail":"{revision:17745; response_revision:20021; }","duration":"142.171335ms","start":"2026-05-26T16:35:58.989308Z","end":"2026-05-26T16:35:59.131479Z","steps":["trace[2061052449] 'process raft request' (duration: 90.199716ms)","trace[2061052449] 'check and update compact revision' (duration: 51.848466ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:35:59.131508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17745} {"level":"info","ts":"2026-05-26T16:35:59.232764Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17745,"took":"97.677167ms","hash":584570967,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":33992704,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T16:35:59.233041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":584570967,"revision":17745,"compact-revision":15335} {"level":"info","ts":"2026-05-26T16:36:04.479614Z","caller":"traceutil/trace.go:171","msg":"trace[1027091084] linearizableReadLoop","detail":"{readStateIndex:20730; appliedIndex:20729; }","duration":"113.004589ms","start":"2026-05-26T16:36:04.366584Z","end":"2026-05-26T16:36:04.479589Z","steps":["trace[1027091084] 'read index received' (duration: 112.838925ms)","trace[1027091084] 'applied index is now lower than readState.Index' (duration: 164.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:36:04.479715Z","caller":"traceutil/trace.go:171","msg":"trace[1804224826] transaction","detail":"{read_only:false; response_revision:20049; number_of_response:1; }","duration":"170.551413ms","start":"2026-05-26T16:36:04.309131Z","end":"2026-05-26T16:36:04.479683Z","steps":["trace[1804224826] 'process raft request' (duration: 170.295607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:36:04.479742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.139512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:36:04.479792Z","caller":"traceutil/trace.go:171","msg":"trace[562361680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20049; }","duration":"113.234985ms","start":"2026-05-26T16:36:04.366544Z","end":"2026-05-26T16:36:04.479779Z","steps":["trace[562361680] 'agreement among raft nodes before linearized reading' (duration: 113.138602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:36:04.754228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.754359ms","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-26T16:36:04.7543Z","caller":"traceutil/trace.go:171","msg":"trace[1741177261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20049; }","duration":"149.909073ms","start":"2026-05-26T16:36:04.604376Z","end":"2026-05-26T16:36:04.754285Z","steps":["trace[1741177261] 'range keys from in-memory index tree' (duration: 149.581976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:36:04.754521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.822567ms","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-26T16:36:04.75454Z","caller":"traceutil/trace.go:171","msg":"trace[334002540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20049; }","duration":"155.874329ms","start":"2026-05-26T16:36:04.59866Z","end":"2026-05-26T16:36:04.754535Z","steps":["trace[334002540] 'range keys from in-memory index tree' (duration: 155.703324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:36:04.754605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.334068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-26T16:36:04.754685Z","caller":"traceutil/trace.go:171","msg":"trace[776559951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20049; }","duration":"160.459541ms","start":"2026-05-26T16:36:04.594206Z","end":"2026-05-26T16:36:04.754666Z","steps":["trace[776559951] 'range keys from in-memory index tree' (duration: 160.078081ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:36:04.911718Z","caller":"traceutil/trace.go:171","msg":"trace[127193802] transaction","detail":"{read_only:false; response_revision:20050; number_of_response:1; }","duration":"153.631014ms","start":"2026-05-26T16:36:04.758063Z","end":"2026-05-26T16:36:04.911694Z","steps":["trace[127193802] 'process raft request' (duration: 153.49069ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:36:04.913326Z","caller":"traceutil/trace.go:171","msg":"trace[1768433744] transaction","detail":"{read_only:false; response_revision:20051; number_of_response:1; }","duration":"155.13996ms","start":"2026-05-26T16:36:04.758153Z","end":"2026-05-26T16:36:04.913293Z","steps":["trace[1768433744] 'process raft request' (duration: 154.906704ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:36:04.913467Z","caller":"traceutil/trace.go:171","msg":"trace[124270819] transaction","detail":"{read_only:false; response_revision:20052; number_of_response:1; }","duration":"155.214332ms","start":"2026-05-26T16:36:04.758229Z","end":"2026-05-26T16:36:04.913443Z","steps":["trace[124270819] 'process raft request' (duration: 154.991736ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:36:05.451183Z","caller":"traceutil/trace.go:171","msg":"trace[849818418] transaction","detail":"{read_only:false; response_revision:20055; number_of_response:1; }","duration":"169.672982ms","start":"2026-05-26T16:36:05.281481Z","end":"2026-05-26T16:36:05.451154Z","steps":["trace[849818418] 'process raft request' (duration: 169.497438ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:39:47.676346Z","caller":"traceutil/trace.go:171","msg":"trace[985309972] linearizableReadLoop","detail":"{readStateIndex:23150; appliedIndex:23149; }","duration":"102.807532ms","start":"2026-05-26T16:39:47.573515Z","end":"2026-05-26T16:39:47.676322Z","steps":["trace[985309972] 'read index received' (duration: 102.604717ms)","trace[985309972] 'applied index is now lower than readState.Index' (duration: 201.685µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:39:47.676438Z","caller":"traceutil/trace.go:171","msg":"trace[657328266] transaction","detail":"{read_only:false; response_revision:22400; number_of_response:1; }","duration":"174.833928ms","start":"2026-05-26T16:39:47.501567Z","end":"2026-05-26T16:39:47.676401Z","steps":["trace[657328266] 'process raft request' (duration: 174.558221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:39:47.676583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.053867ms","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-26T16:39:47.676626Z","caller":"traceutil/trace.go:171","msg":"trace[1774268102] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:22400; }","duration":"103.114319ms","start":"2026-05-26T16:39:47.573501Z","end":"2026-05-26T16:39:47.676615Z","steps":["trace[1774268102] 'agreement among raft nodes before linearized reading' (duration: 102.957765ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:39:47.834025Z","caller":"traceutil/trace.go:171","msg":"trace[1633778438] linearizableReadLoop","detail":"{readStateIndex:23151; appliedIndex:23150; }","duration":"157.57185ms","start":"2026-05-26T16:39:47.676432Z","end":"2026-05-26T16:39:47.834004Z","steps":["trace[1633778438] 'read index received' (duration: 156.791841ms)","trace[1633778438] 'applied index is now lower than readState.Index' (duration: 778.959µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:39:47.834102Z","caller":"traceutil/trace.go:171","msg":"trace[2091956137] transaction","detail":"{read_only:false; response_revision:22401; number_of_response:1; }","duration":"215.34074ms","start":"2026-05-26T16:39:47.618735Z","end":"2026-05-26T16:39:47.834075Z","steps":["trace[2091956137] 'process raft request' (duration: 214.478568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:39:47.834181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.86912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-05-26T16:39:47.834203Z","caller":"traceutil/trace.go:171","msg":"trace[849576988] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22401; }","duration":"245.907061ms","start":"2026-05-26T16:39:47.588287Z","end":"2026-05-26T16:39:47.834195Z","steps":["trace[849576988] 'agreement among raft nodes before linearized reading' (duration: 245.788978ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:39:47.835913Z","caller":"traceutil/trace.go:171","msg":"trace[1490826165] transaction","detail":"{read_only:false; response_revision:22402; number_of_response:1; }","duration":"153.626454ms","start":"2026-05-26T16:39:47.682261Z","end":"2026-05-26T16:39:47.835887Z","steps":["trace[1490826165] 'process raft request' (duration: 153.444829ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:40:59.136852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20021} {"level":"info","ts":"2026-05-26T16:40:59.245351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20021,"took":"104.78162ms","hash":862950200,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":41775104,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-26T16:40:59.245437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":862950200,"revision":20021,"compact-revision":17745} {"level":"info","ts":"2026-05-26T16:41:08.50123Z","caller":"traceutil/trace.go:171","msg":"trace[372742217] linearizableReadLoop","detail":"{readStateIndex:24058; appliedIndex:24057; }","duration":"101.141242ms","start":"2026-05-26T16:41:08.400056Z","end":"2026-05-26T16:41:08.501197Z","steps":["trace[372742217] 'read index received' (duration: 47.303937ms)","trace[372742217] 'applied index is now lower than readState.Index' (duration: 53.836015ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:41:08.501327Z","caller":"traceutil/trace.go:171","msg":"trace[448445435] transaction","detail":"{read_only:false; response_revision:23285; number_of_response:1; }","duration":"118.131433ms","start":"2026-05-26T16:41:08.383154Z","end":"2026-05-26T16:41:08.501286Z","steps":["trace[448445435] 'process raft request' (duration: 64.190366ms)","trace[448445435] 'compare' (duration: 53.428675ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:41:08.50169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.615663ms","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-26T16:41:08.501752Z","caller":"traceutil/trace.go:171","msg":"trace[341626597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23285; }","duration":"101.708475ms","start":"2026-05-26T16:41:08.400025Z","end":"2026-05-26T16:41:08.501734Z","steps":["trace[341626597] 'agreement among raft nodes before linearized reading' (duration: 101.338786ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:08.867577Z","caller":"traceutil/trace.go:171","msg":"trace[1769318855] transaction","detail":"{read_only:false; response_revision:24456; number_of_response:1; }","duration":"140.764999ms","start":"2026-05-26T16:43:08.726785Z","end":"2026-05-26T16:43:08.86755Z","steps":["trace[1769318855] 'process raft request' (duration: 140.519523ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:09.080639Z","caller":"traceutil/trace.go:171","msg":"trace[603814005] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"131.946086ms","start":"2026-05-26T16:43:08.948672Z","end":"2026-05-26T16:43:09.080618Z","steps":["trace[603814005] 'process raft request' (duration: 131.820813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:09.233962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.424613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:09.234069Z","caller":"traceutil/trace.go:171","msg":"trace[659431390] linearizableReadLoop","detail":"{readStateIndex:25264; appliedIndex:25263; }","duration":"182.394337ms","start":"2026-05-26T16:43:09.051657Z","end":"2026-05-26T16:43:09.234052Z","steps":["trace[659431390] 'read index received' (duration: 29.545095ms)","trace[659431390] 'applied index is now lower than readState.Index' (duration: 152.848022ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:09.234117Z","caller":"traceutil/trace.go:171","msg":"trace[506340228] transaction","detail":"{read_only:false; response_revision:24459; number_of_response:1; }","duration":"219.056264ms","start":"2026-05-26T16:43:09.015018Z","end":"2026-05-26T16:43:09.234074Z","steps":["trace[506340228] 'process raft request' (duration: 94.426526ms)","trace[506340228] 'compare' (duration: 124.310611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:09.23423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.579731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-26T16:43:09.234423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.540235ms","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-26T16:43:09.234456Z","caller":"traceutil/trace.go:171","msg":"trace[2138961032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24459; }","duration":"100.603576ms","start":"2026-05-26T16:43:09.13384Z","end":"2026-05-26T16:43:09.234444Z","steps":["trace[2138961032] 'agreement among raft nodes before linearized reading' (duration: 100.551395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:09.234217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.583667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:09.234527Z","caller":"traceutil/trace.go:171","msg":"trace[1694866676] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:24459; }","duration":"124.916165ms","start":"2026-05-26T16:43:09.109576Z","end":"2026-05-26T16:43:09.234492Z","steps":["trace[1694866676] 'agreement among raft nodes before linearized reading' (duration: 124.578557ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:09.234271Z","caller":"traceutil/trace.go:171","msg":"trace[7150826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24459; }","duration":"182.652523ms","start":"2026-05-26T16:43:09.051609Z","end":"2026-05-26T16:43:09.234261Z","steps":["trace[7150826] 'agreement among raft nodes before linearized reading' (duration: 182.52563ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:09.414081Z","caller":"traceutil/trace.go:171","msg":"trace[1137931482] transaction","detail":"{read_only:false; response_revision:24460; number_of_response:1; }","duration":"175.89338ms","start":"2026-05-26T16:43:09.238136Z","end":"2026-05-26T16:43:09.41403Z","steps":["trace[1137931482] 'process raft request' (duration: 128.82455ms)","trace[1137931482] 'compare' (duration: 46.750403ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:09.414144Z","caller":"traceutil/trace.go:171","msg":"trace[839694580] transaction","detail":"{read_only:false; response_revision:24461; number_of_response:1; }","duration":"175.376266ms","start":"2026-05-26T16:43:09.238737Z","end":"2026-05-26T16:43:09.414113Z","steps":["trace[839694580] 'process raft request' (duration: 175.179241ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:09.414581Z","caller":"traceutil/trace.go:171","msg":"trace[2028155384] transaction","detail":"{read_only:false; response_revision:24462; number_of_response:1; }","duration":"175.062509ms","start":"2026-05-26T16:43:09.239506Z","end":"2026-05-26T16:43:09.414569Z","steps":["trace[2028155384] 'process raft request' (duration: 174.465675ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:09.583277Z","caller":"traceutil/trace.go:171","msg":"trace[91315237] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"162.958776ms","start":"2026-05-26T16:43:09.420262Z","end":"2026-05-26T16:43:09.58322Z","steps":["trace[91315237] 'process raft request' (duration: 144.768786ms)","trace[91315237] 'compare' (duration: 17.945324ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:09.583361Z","caller":"traceutil/trace.go:171","msg":"trace[2022572801] linearizableReadLoop","detail":"{readStateIndex:25269; appliedIndex:25268; }","duration":"142.362378ms","start":"2026-05-26T16:43:09.440969Z","end":"2026-05-26T16:43:09.583332Z","steps":["trace[2022572801] 'read index received' (duration: 124.166437ms)","trace[2022572801] 'applied index is now lower than readState.Index' (duration: 18.194501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:09.58355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.569903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-26T16:43:09.583607Z","caller":"traceutil/trace.go:171","msg":"trace[967337230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24465; }","duration":"142.725296ms","start":"2026-05-26T16:43:09.440868Z","end":"2026-05-26T16:43:09.583593Z","steps":["trace[967337230] 'agreement among raft nodes before linearized reading' (duration: 142.530281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:09.906102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.674433ms","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-26T16:43:09.906187Z","caller":"traceutil/trace.go:171","msg":"trace[1110776845] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24466; }","duration":"215.802586ms","start":"2026-05-26T16:43:09.690362Z","end":"2026-05-26T16:43:09.906164Z","steps":["trace[1110776845] 'range keys from in-memory index tree' (duration: 215.452827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:09.906266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.044507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-05-26T16:43:09.906322Z","caller":"traceutil/trace.go:171","msg":"trace[319859865] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:24466; }","duration":"146.149689ms","start":"2026-05-26T16:43:09.760158Z","end":"2026-05-26T16:43:09.906308Z","steps":["trace[319859865] 'range keys from in-memory index tree' (duration: 145.901103ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:09.909167Z","caller":"traceutil/trace.go:171","msg":"trace[1511861465] transaction","detail":"{read_only:false; response_revision:24467; number_of_response:1; }","duration":"100.724929ms","start":"2026-05-26T16:43:09.808429Z","end":"2026-05-26T16:43:09.909154Z","steps":["trace[1511861465] 'process raft request' (duration: 100.613976ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:10.450478Z","caller":"traceutil/trace.go:171","msg":"trace[2009847285] transaction","detail":"{read_only:false; response_revision:24468; number_of_response:1; }","duration":"105.534126ms","start":"2026-05-26T16:43:10.344913Z","end":"2026-05-26T16:43:10.450448Z","steps":["trace[2009847285] 'process raft request' (duration: 105.344761ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:10.79421Z","caller":"traceutil/trace.go:171","msg":"trace[1211034286] linearizableReadLoop","detail":"{readStateIndex:25274; appliedIndex:25273; }","duration":"289.05873ms","start":"2026-05-26T16:43:10.505123Z","end":"2026-05-26T16:43:10.794182Z","steps":["trace[1211034286] 'read index received' (duration: 288.753453ms)","trace[1211034286] 'applied index is now lower than readState.Index' (duration: 303.777µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:10.794295Z","caller":"traceutil/trace.go:171","msg":"trace[745141575] transaction","detail":"{read_only:false; response_revision:24469; number_of_response:1; }","duration":"312.375226ms","start":"2026-05-26T16:43:10.481892Z","end":"2026-05-26T16:43:10.794267Z","steps":["trace[745141575] 'process raft request' (duration: 312.092569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:10.794455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.289696ms","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-26T16:43:10.794517Z","caller":"traceutil/trace.go:171","msg":"trace[969640818] 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:24469; }","duration":"289.386888ms","start":"2026-05-26T16:43:10.505112Z","end":"2026-05-26T16:43:10.794499Z","steps":["trace[969640818] 'agreement among raft nodes before linearized reading' (duration: 289.249415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:10.794531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:10.48187Z","time spent":"312.483778ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:43:10.795663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.736216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-26T16:43:10.795728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.116945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:10.795742Z","caller":"traceutil/trace.go:171","msg":"trace[2106505212] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24469; }","duration":"104.869069ms","start":"2026-05-26T16:43:10.690855Z","end":"2026-05-26T16:43:10.795724Z","steps":["trace[2106505212] 'agreement among raft nodes before linearized reading' (duration: 104.718306ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:10.795766Z","caller":"traceutil/trace.go:171","msg":"trace[1464342763] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24469; }","duration":"112.184857ms","start":"2026-05-26T16:43:10.683571Z","end":"2026-05-26T16:43:10.795756Z","steps":["trace[1464342763] 'agreement among raft nodes before linearized reading' (duration: 112.081135ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:10.98091Z","caller":"traceutil/trace.go:171","msg":"trace[1906055394] transaction","detail":"{read_only:false; response_revision:24470; number_of_response:1; }","duration":"107.414851ms","start":"2026-05-26T16:43:10.873447Z","end":"2026-05-26T16:43:10.980862Z","steps":["trace[1906055394] 'process raft request' (duration: 107.253437ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:11.286319Z","caller":"traceutil/trace.go:171","msg":"trace[1252701075] transaction","detail":"{read_only:false; response_revision:24472; number_of_response:1; }","duration":"198.991619ms","start":"2026-05-26T16:43:11.087289Z","end":"2026-05-26T16:43:11.28628Z","steps":["trace[1252701075] 'process raft request' (duration: 193.739303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:11.637206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.253486ms","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":"warn","ts":"2026-05-26T16:43:11.63703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.993975ms","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-26T16:43:11.637272Z","caller":"traceutil/trace.go:171","msg":"trace[253342469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24473; }","duration":"218.336508ms","start":"2026-05-26T16:43:11.418917Z","end":"2026-05-26T16:43:11.637254Z","steps":["trace[253342469] 'range keys from in-memory index tree' (duration: 218.157393ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:11.63727Z","caller":"traceutil/trace.go:171","msg":"trace[1294889266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24473; }","duration":"219.276511ms","start":"2026-05-26T16:43:11.417979Z","end":"2026-05-26T16:43:11.637256Z","steps":["trace[1294889266] 'range keys from in-memory index tree' (duration: 218.851041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:11.63733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.797085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-26T16:43:11.637397Z","caller":"traceutil/trace.go:171","msg":"trace[500131303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24473; }","duration":"217.899428ms","start":"2026-05-26T16:43:11.419482Z","end":"2026-05-26T16:43:11.637382Z","steps":["trace[500131303] 'range keys from in-memory index tree' (duration: 217.657791ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:11.640737Z","caller":"traceutil/trace.go:171","msg":"trace[1587322330] transaction","detail":"{read_only:false; response_revision:24474; number_of_response:1; }","duration":"219.272289ms","start":"2026-05-26T16:43:11.421453Z","end":"2026-05-26T16:43:11.640726Z","steps":["trace[1587322330] 'process raft request' (duration: 219.111576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:11.987919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.652332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:11.988083Z","caller":"traceutil/trace.go:171","msg":"trace[891660137] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"399.821463ms","start":"2026-05-26T16:43:11.588243Z","end":"2026-05-26T16:43:11.988064Z","steps":["trace[891660137] 'process raft request' (duration: 176.939395ms)","trace[891660137] 'compare' (duration: 222.028408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:11.988162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.588218Z","time spent":"399.907475ms","remote":"127.0.0.1:41378","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-26T16:43:11.988253Z","caller":"traceutil/trace.go:171","msg":"trace[2008416420] linearizableReadLoop","detail":"{readStateIndex:25281; appliedIndex:25279; }","duration":"347.589707ms","start":"2026-05-26T16:43:11.64065Z","end":"2026-05-26T16:43:11.98824Z","steps":["trace[2008416420] 'read index received' (duration: 124.545015ms)","trace[2008416420] 'applied index is now lower than readState.Index' (duration: 223.043632ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:11.9884Z","caller":"traceutil/trace.go:171","msg":"trace[853335516] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"347.541767ms","start":"2026-05-26T16:43:11.64085Z","end":"2026-05-26T16:43:11.988392Z","steps":["trace[853335516] 'process raft request' (duration: 347.24133ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:11.988436Z","caller":"traceutil/trace.go:171","msg":"trace[1131642291] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"347.532417ms","start":"2026-05-26T16:43:11.640892Z","end":"2026-05-26T16:43:11.988424Z","steps":["trace[1131642291] 'process raft request' (duration: 347.23812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:11.988455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.640842Z","time spent":"347.582528ms","remote":"127.0.0.1:41378","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-26T16:43:11.988518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.640875Z","time spent":"347.591908ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:43:11.988609Z","caller":"traceutil/trace.go:171","msg":"trace[1623751895] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"281.681241ms","start":"2026-05-26T16:43:11.706919Z","end":"2026-05-26T16:43:11.9886Z","steps":["trace[1623751895] 'process raft request' (duration: 281.247191ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:11.988632Z","caller":"traceutil/trace.go:171","msg":"trace[1893258825] transaction","detail":"{read_only:false; response_revision:24476; number_of_response:1; }","duration":"348.042948ms","start":"2026-05-26T16:43:11.640582Z","end":"2026-05-26T16:43:11.988625Z","steps":["trace[1893258825] 'process raft request' (duration: 347.440344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:11.988678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.640565Z","time spent":"348.090849ms","remote":"127.0.0.1:41378","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-26T16:43:11.9884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.342916ms","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-26T16:43:11.988739Z","caller":"traceutil/trace.go:171","msg":"trace[463821203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24479; }","duration":"400.704264ms","start":"2026-05-26T16:43:11.588028Z","end":"2026-05-26T16:43:11.988733Z","steps":["trace[463821203] 'agreement among raft nodes before linearized reading' (duration: 400.261453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:11.988763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.588021Z","time spent":"400.736905ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T16:43:11.988867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.039296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-26T16:43:11.988904Z","caller":"traceutil/trace.go:171","msg":"trace[1507004637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24479; }","duration":"376.077077ms","start":"2026-05-26T16:43:11.612818Z","end":"2026-05-26T16:43:11.988895Z","steps":["trace[1507004637] 'agreement among raft nodes before linearized reading' (duration: 375.968154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:11.988938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.61275Z","time spent":"376.18216ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T16:43:12.25628Z","caller":"traceutil/trace.go:171","msg":"trace[1076689474] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"262.180259ms","start":"2026-05-26T16:43:11.994074Z","end":"2026-05-26T16:43:12.256254Z","steps":["trace[1076689474] 'process raft request' (duration: 261.984405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:12.500158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.906828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:12.500328Z","caller":"traceutil/trace.go:171","msg":"trace[1324994645] linearizableReadLoop","detail":"{readStateIndex:25286; appliedIndex:25284; }","duration":"501.187508ms","start":"2026-05-26T16:43:11.999128Z","end":"2026-05-26T16:43:12.500316Z","steps":["trace[1324994645] 'read index received' (duration: 256.872651ms)","trace[1324994645] 'applied index is now lower than readState.Index' (duration: 244.314067ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:12.500323Z","caller":"traceutil/trace.go:171","msg":"trace[1007619555] transaction","detail":"{read_only:false; response_revision:24481; number_of_response:1; }","duration":"504.465806ms","start":"2026-05-26T16:43:11.995825Z","end":"2026-05-26T16:43:12.500291Z","steps":["trace[1007619555] 'process raft request' (duration: 364.419244ms)","trace[1007619555] 'compare' (duration: 139.383506ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:12.500408Z","caller":"traceutil/trace.go:171","msg":"trace[623793483] transaction","detail":"{read_only:false; response_revision:24482; number_of_response:1; }","duration":"181.958527ms","start":"2026-05-26T16:43:12.318443Z","end":"2026-05-26T16:43:12.500401Z","steps":["trace[623793483] 'process raft request' (duration: 181.823204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:12.500481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.995812Z","time spent":"504.59458ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:43:12.500538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.393652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.225\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-26T16:43:12.500619Z","caller":"traceutil/trace.go:171","msg":"trace[1143836793] range","detail":"{range_begin:/registry/masterleases/199.19.213.225; range_end:; response_count:1; response_revision:24481; }","duration":"501.499935ms","start":"2026-05-26T16:43:11.999099Z","end":"2026-05-26T16:43:12.500599Z","steps":["trace[1143836793] 'agreement among raft nodes before linearized reading' (duration: 501.268489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:12.500665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:11.999092Z","time spent":"501.564667ms","remote":"127.0.0.1:41196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.19.213.225\" "} {"level":"warn","ts":"2026-05-26T16:43:12.518805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.636634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:12.518901Z","caller":"traceutil/trace.go:171","msg":"trace[822834056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24483; }","duration":"149.770127ms","start":"2026-05-26T16:43:12.369112Z","end":"2026-05-26T16:43:12.518882Z","steps":["trace[822834056] 'agreement among raft nodes before linearized reading' (duration: 149.587603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:12.519456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.64174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T16:43:12.519513Z","caller":"traceutil/trace.go:171","msg":"trace[716733202] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:24483; }","duration":"110.734453ms","start":"2026-05-26T16:43:12.408765Z","end":"2026-05-26T16:43:12.5195Z","steps":["trace[716733202] 'agreement among raft nodes before linearized reading' (duration: 110.62884ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:12.997793Z","caller":"traceutil/trace.go:171","msg":"trace[1463434758] linearizableReadLoop","detail":"{readStateIndex:25291; appliedIndex:25290; }","duration":"222.59426ms","start":"2026-05-26T16:43:12.775177Z","end":"2026-05-26T16:43:12.997771Z","steps":["trace[1463434758] 'read index received' (duration: 222.430756ms)","trace[1463434758] 'applied index is now lower than readState.Index' (duration: 162.614µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:12.998207Z","caller":"traceutil/trace.go:171","msg":"trace[484540734] transaction","detail":"{read_only:false; response_revision:24485; number_of_response:1; }","duration":"234.237143ms","start":"2026-05-26T16:43:12.763934Z","end":"2026-05-26T16:43:12.998172Z","steps":["trace[484540734] 'process raft request' (duration: 233.294969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:12.998399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.143614ms","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-26T16:43:12.998453Z","caller":"traceutil/trace.go:171","msg":"trace[1670770590] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24485; }","duration":"223.312918ms","start":"2026-05-26T16:43:12.775127Z","end":"2026-05-26T16:43:12.99844Z","steps":["trace[1670770590] 'agreement among raft nodes before linearized reading' (duration: 223.088173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:12.998872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.720495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T16:43:12.999017Z","caller":"traceutil/trace.go:171","msg":"trace[1569475173] transaction","detail":"{read_only:false; response_revision:24486; number_of_response:1; }","duration":"198.043707ms","start":"2026-05-26T16:43:12.800956Z","end":"2026-05-26T16:43:12.999Z","steps":["trace[1569475173] 'process raft request' (duration: 197.631157ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:12.998926Z","caller":"traceutil/trace.go:171","msg":"trace[1820990539] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:24487; }","duration":"132.816907ms","start":"2026-05-26T16:43:12.866097Z","end":"2026-05-26T16:43:12.998914Z","steps":["trace[1820990539] 'agreement among raft nodes before linearized reading' (duration: 132.627892ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:13.196148Z","caller":"traceutil/trace.go:171","msg":"trace[1816097732] transaction","detail":"{read_only:false; response_revision:24488; number_of_response:1; }","duration":"182.495359ms","start":"2026-05-26T16:43:13.013625Z","end":"2026-05-26T16:43:13.19612Z","steps":["trace[1816097732] 'process raft request' (duration: 84.827014ms)","trace[1816097732] 'compare' (duration: 97.266575ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:13.589701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.149688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:13.590084Z","caller":"traceutil/trace.go:171","msg":"trace[1879102214] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"298.357496ms","start":"2026-05-26T16:43:13.291714Z","end":"2026-05-26T16:43:13.590071Z","steps":["trace[1879102214] 'process raft request' (duration: 73.768257ms)","trace[1879102214] 'compare' (duration: 223.78632ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:13.590191Z","caller":"traceutil/trace.go:171","msg":"trace[854879730] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"297.138606ms","start":"2026-05-26T16:43:13.293045Z","end":"2026-05-26T16:43:13.590184Z","steps":["trace[854879730] 'process raft request' (duration: 296.733916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:13.944747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.721282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:13.945063Z","caller":"traceutil/trace.go:171","msg":"trace[184396103] linearizableReadLoop","detail":"{readStateIndex:25297; appliedIndex:25296; }","duration":"273.050884ms","start":"2026-05-26T16:43:13.671988Z","end":"2026-05-26T16:43:13.945039Z","steps":["trace[184396103] 'read index received' (duration: 125.850509ms)","trace[184396103] 'applied index is now lower than readState.Index' (duration: 147.197905ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:13.945228Z","caller":"traceutil/trace.go:171","msg":"trace[553195334] transaction","detail":"{read_only:false; response_revision:24491; number_of_response:1; }","duration":"299.35156ms","start":"2026-05-26T16:43:13.645861Z","end":"2026-05-26T16:43:13.945212Z","steps":["trace[553195334] 'process raft request' (duration: 152.015512ms)","trace[553195334] 'compare' (duration: 146.442895ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:13.945352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.101908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T16:43:13.945393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.95633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T16:43:13.945505Z","caller":"traceutil/trace.go:171","msg":"trace[143708086] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24491; }","duration":"211.144284ms","start":"2026-05-26T16:43:13.734343Z","end":"2026-05-26T16:43:13.945487Z","steps":["trace[143708086] 'agreement among raft nodes before linearized reading' (duration: 210.939389ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:13.945442Z","caller":"traceutil/trace.go:171","msg":"trace[1410655047] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24491; }","duration":"262.230941ms","start":"2026-05-26T16:43:13.683188Z","end":"2026-05-26T16:43:13.945419Z","steps":["trace[1410655047] 'agreement among raft nodes before linearized reading' (duration: 262.076227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:13.945352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.33931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:13.945689Z","caller":"traceutil/trace.go:171","msg":"trace[636687938] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:24491; }","duration":"273.72057ms","start":"2026-05-26T16:43:13.671949Z","end":"2026-05-26T16:43:13.94567Z","steps":["trace[636687938] 'agreement among raft nodes before linearized reading' (duration: 273.32958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:14.252116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.655565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:14.252391Z","caller":"traceutil/trace.go:171","msg":"trace[172572303] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"250.902766ms","start":"2026-05-26T16:43:14.001471Z","end":"2026-05-26T16:43:14.252374Z","steps":["trace[172572303] 'process raft request' (duration: 250.861745ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:14.25252Z","caller":"traceutil/trace.go:171","msg":"trace[1593576481] transaction","detail":"{read_only:false; response_revision:24493; number_of_response:1; }","duration":"254.105983ms","start":"2026-05-26T16:43:13.998406Z","end":"2026-05-26T16:43:14.252512Z","steps":["trace[1593576481] 'process raft request' (duration: 253.839917ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:14.252598Z","caller":"traceutil/trace.go:171","msg":"trace[1807064703] transaction","detail":"{read_only:false; response_revision:24494; number_of_response:1; }","duration":"253.964659ms","start":"2026-05-26T16:43:13.998626Z","end":"2026-05-26T16:43:14.252591Z","steps":["trace[1807064703] 'process raft request' (duration: 253.674742ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:14.252688Z","caller":"traceutil/trace.go:171","msg":"trace[1358306512] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"256.206674ms","start":"2026-05-26T16:43:13.996446Z","end":"2026-05-26T16:43:14.252653Z","steps":["trace[1358306512] 'process raft request' (duration: 93.940604ms)","trace[1358306512] 'compare' (duration: 161.45103ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:24.242657Z","caller":"traceutil/trace.go:171","msg":"trace[696031361] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"256.999593ms","start":"2026-05-26T16:43:23.985383Z","end":"2026-05-26T16:43:24.242383Z","steps":["trace[696031361] 'process raft request' (duration: 256.833109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:24.52606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.276036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-26T16:43:24.526138Z","caller":"traceutil/trace.go:171","msg":"trace[410149053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24565; }","duration":"225.395509ms","start":"2026-05-26T16:43:24.300723Z","end":"2026-05-26T16:43:24.526119Z","steps":["trace[410149053] 'range keys from in-memory index tree' (duration: 225.124673ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:24.542049Z","caller":"traceutil/trace.go:171","msg":"trace[1191046181] linearizableReadLoop","detail":"{readStateIndex:25374; appliedIndex:25373; }","duration":"239.330885ms","start":"2026-05-26T16:43:24.302703Z","end":"2026-05-26T16:43:24.542034Z","steps":["trace[1191046181] 'read index received' (duration: 239.242473ms)","trace[1191046181] 'applied index is now lower than readState.Index' (duration: 87.682µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:24.542089Z","caller":"traceutil/trace.go:171","msg":"trace[310937601] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"241.161571ms","start":"2026-05-26T16:43:24.300907Z","end":"2026-05-26T16:43:24.542069Z","steps":["trace[310937601] 'process raft request' (duration: 241.015967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:24.542236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.51821ms","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-26T16:43:24.542905Z","caller":"traceutil/trace.go:171","msg":"trace[461817540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24566; }","duration":"240.208158ms","start":"2026-05-26T16:43:24.302656Z","end":"2026-05-26T16:43:24.542864Z","steps":["trace[461817540] 'agreement among raft nodes before linearized reading' (duration: 239.454789ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:24.727792Z","caller":"traceutil/trace.go:171","msg":"trace[1230034411] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"196.190762ms","start":"2026-05-26T16:43:24.531566Z","end":"2026-05-26T16:43:24.727757Z","steps":["trace[1230034411] 'process raft request' (duration: 195.815382ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:24.727709Z","caller":"traceutil/trace.go:171","msg":"trace[697183531] linearizableReadLoop","detail":"{readStateIndex:25375; appliedIndex:25374; }","duration":"185.594266ms","start":"2026-05-26T16:43:24.542094Z","end":"2026-05-26T16:43:24.727689Z","steps":["trace[697183531] 'read index received' (duration: 185.332999ms)","trace[697183531] 'applied index is now lower than readState.Index' (duration: 260.427µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:24.727954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.986318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-26T16:43:24.72787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.706287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T16:43:24.727987Z","caller":"traceutil/trace.go:171","msg":"trace[1027587079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24567; }","duration":"329.08248ms","start":"2026-05-26T16:43:24.398896Z","end":"2026-05-26T16:43:24.727979Z","steps":["trace[1027587079] 'agreement among raft nodes before linearized reading' (duration: 328.930476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:24.728006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.398874Z","time spent":"329.127181ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T16:43:24.728074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.695325ms","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-26T16:43:24.728049Z","caller":"traceutil/trace.go:171","msg":"trace[2021423615] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24567; }","duration":"258.87153ms","start":"2026-05-26T16:43:24.46913Z","end":"2026-05-26T16:43:24.728001Z","steps":["trace[2021423615] 'agreement among raft nodes before linearized reading' (duration: 258.654255ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:24.728104Z","caller":"traceutil/trace.go:171","msg":"trace[2064821131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24567; }","duration":"424.740547ms","start":"2026-05-26T16:43:24.303354Z","end":"2026-05-26T16:43:24.728095Z","steps":["trace[2064821131] 'agreement among raft nodes before linearized reading' (duration: 424.636654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:24.728135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.303347Z","time spent":"424.780997ms","remote":"127.0.0.1:41378","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-26T16:43:25.13012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.856463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:25.130355Z","caller":"traceutil/trace.go:171","msg":"trace[898810576] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"396.176664ms","start":"2026-05-26T16:43:24.734162Z","end":"2026-05-26T16:43:25.130339Z","steps":["trace[898810576] 'process raft request' (duration: 396.137863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.130421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.734142Z","time spent":"396.249556ms","remote":"127.0.0.1:41378","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-26T16:43:25.130846Z","caller":"traceutil/trace.go:171","msg":"trace[1404976515] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"582.974598ms","start":"2026-05-26T16:43:24.547856Z","end":"2026-05-26T16:43:25.130831Z","steps":["trace[1404976515] 'process raft request' (duration: 265.336296ms)","trace[1404976515] 'compare' (duration: 316.003103ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:25.130908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.547836Z","time spent":"583.04013ms","remote":"127.0.0.1:41378","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-26T16:43:25.131043Z","caller":"traceutil/trace.go:171","msg":"trace[461570143] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"550.628375ms","start":"2026-05-26T16:43:24.580406Z","end":"2026-05-26T16:43:25.131034Z","steps":["trace[461570143] 'process raft request' (duration: 549.796004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.1311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.580384Z","time spent":"550.686457ms","remote":"127.0.0.1:41378","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-26T16:43:25.131224Z","caller":"traceutil/trace.go:171","msg":"trace[758601274] linearizableReadLoop","detail":"{readStateIndex:25377; appliedIndex:25375; }","duration":"403.242535ms","start":"2026-05-26T16:43:24.727973Z","end":"2026-05-26T16:43:25.131215Z","steps":["trace[758601274] 'read index received' (duration: 85.229953ms)","trace[758601274] 'applied index is now lower than readState.Index' (duration: 318.011562ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:25.131284Z","caller":"traceutil/trace.go:171","msg":"trace[2081543765] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"397.552418ms","start":"2026-05-26T16:43:24.733724Z","end":"2026-05-26T16:43:25.131276Z","steps":["trace[2081543765] 'process raft request' (duration: 396.540183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.131327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.733704Z","time spent":"397.596979ms","remote":"127.0.0.1:41378","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-26T16:43:25.131591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.313456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T16:43:25.131632Z","caller":"traceutil/trace.go:171","msg":"trace[68198830] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24571; }","duration":"533.381108ms","start":"2026-05-26T16:43:24.598239Z","end":"2026-05-26T16:43:25.13162Z","steps":["trace[68198830] 'agreement among raft nodes before linearized reading' (duration: 533.313226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.131668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.598227Z","time spent":"533.433299ms","remote":"127.0.0.1:35024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":31,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-05-26T16:43:25.13202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.968551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-26T16:43:25.132058Z","caller":"traceutil/trace.go:171","msg":"trace[1605739058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24571; }","duration":"572.049893ms","start":"2026-05-26T16:43:24.559999Z","end":"2026-05-26T16:43:25.132049Z","steps":["trace[1605739058] 'agreement among raft nodes before linearized reading' (duration: 571.92802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.132092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.55998Z","time spent":"572.104365ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T16:43:25.632231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16815489265323230639,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T16:43:25.680362Z","caller":"traceutil/trace.go:171","msg":"trace[6950056] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"641.419303ms","start":"2026-05-26T16:43:25.038918Z","end":"2026-05-26T16:43:25.680337Z","steps":["trace[6950056] 'process raft request' (duration: 641.28848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.680492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.038898Z","time spent":"641.527247ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:43:25.68057Z","caller":"traceutil/trace.go:171","msg":"trace[341650045] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"640.800829ms","start":"2026-05-26T16:43:25.039736Z","end":"2026-05-26T16:43:25.680537Z","steps":["trace[341650045] 'process raft request' (duration: 640.563074ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:25.680678Z","caller":"traceutil/trace.go:171","msg":"trace[1168383752] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"758.035458ms","start":"2026-05-26T16:43:24.922627Z","end":"2026-05-26T16:43:25.680662Z","steps":["trace[1168383752] 'process raft request' (duration: 735.618545ms)","trace[1168383752] 'compare' (duration: 21.606513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:25.680721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.039728Z","time spent":"640.908752ms","remote":"127.0.0.1:41378","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-26T16:43:25.680735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.922606Z","time spent":"758.099199ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:43:25.764868Z","caller":"traceutil/trace.go:171","msg":"trace[419049374] linearizableReadLoop","detail":"{readStateIndex:25383; appliedIndex:25379; }","duration":"633.592614ms","start":"2026-05-26T16:43:25.131249Z","end":"2026-05-26T16:43:25.764841Z","steps":["trace[419049374] 'read index received' (duration: 527.089585ms)","trace[419049374] 'applied index is now lower than readState.Index' (duration: 106.501919ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:25.764993Z","caller":"traceutil/trace.go:171","msg":"trace[2128046216] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"628.572933ms","start":"2026-05-26T16:43:25.136408Z","end":"2026-05-26T16:43:25.764981Z","steps":["trace[2128046216] 'process raft request' (duration: 628.211695ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:25.765112Z","caller":"traceutil/trace.go:171","msg":"trace[984232546] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"123.157003ms","start":"2026-05-26T16:43:25.641933Z","end":"2026-05-26T16:43:25.76509Z","steps":["trace[984232546] 'process raft request' (duration: 122.825485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.765209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.136387Z","time spent":"628.640445ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:43:25.765396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.999388ms","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-26T16:43:25.76542Z","caller":"traceutil/trace.go:171","msg":"trace[1222858696] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24577; }","duration":"630.063199ms","start":"2026-05-26T16:43:25.135351Z","end":"2026-05-26T16:43:25.765415Z","steps":["trace[1222858696] 'agreement among raft nodes before linearized reading' (duration: 629.966076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.765436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.135336Z","time spent":"630.09623ms","remote":"127.0.0.1:41324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-26T16:43:25.765559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004048245s","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-26T16:43:25.765581Z","caller":"traceutil/trace.go:171","msg":"trace[460268006] 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:24577; }","duration":"1.004110786s","start":"2026-05-26T16:43:24.761462Z","end":"2026-05-26T16:43:25.765572Z","steps":["trace[460268006] 'agreement among raft nodes before linearized reading' (duration: 1.004060275s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.765595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:24.761444Z","time spent":"1.004147608s","remote":"127.0.0.1:44652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T16:43:25.76571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.784306ms","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-26T16:43:25.765767Z","caller":"traceutil/trace.go:171","msg":"trace[1196611486] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24577; }","duration":"509.861958ms","start":"2026-05-26T16:43:25.255885Z","end":"2026-05-26T16:43:25.765747Z","steps":["trace[1196611486] 'agreement among raft nodes before linearized reading' (duration: 509.720044ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:25.765825Z","caller":"traceutil/trace.go:171","msg":"trace[400605601] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"119.453723ms","start":"2026-05-26T16:43:25.64635Z","end":"2026-05-26T16:43:25.765804Z","steps":["trace[400605601] 'process raft request' (duration: 118.449969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.765836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.255868Z","time spent":"509.930779ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-26T16:43:25.765945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.776197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:25.765966Z","caller":"traceutil/trace.go:171","msg":"trace[334080603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24577; }","duration":"398.830909ms","start":"2026-05-26T16:43:25.367129Z","end":"2026-05-26T16:43:25.76596Z","steps":["trace[334080603] 'agreement among raft nodes before linearized reading' (duration: 398.795618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.765977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.367109Z","time spent":"398.865799ms","remote":"127.0.0.1:41168","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-26T16:43:25.766936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.527987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-26T16:43:25.76715Z","caller":"traceutil/trace.go:171","msg":"trace[656298583] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24577; }","duration":"743.814664ms","start":"2026-05-26T16:43:25.023316Z","end":"2026-05-26T16:43:25.767131Z","steps":["trace[656298583] 'agreement among raft nodes before linearized reading' (duration: 741.734223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:25.767225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.023297Z","time spent":"743.913547ms","remote":"127.0.0.1:41226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2619,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-26T16:43:26.12298Z","caller":"traceutil/trace.go:171","msg":"trace[516898226] linearizableReadLoop","detail":"{readStateIndex:25386; appliedIndex:25385; }","duration":"350.669283ms","start":"2026-05-26T16:43:25.772291Z","end":"2026-05-26T16:43:26.122961Z","steps":["trace[516898226] 'read index received' (duration: 350.530129ms)","trace[516898226] 'applied index is now lower than readState.Index' (duration: 138.444µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:26.123049Z","caller":"traceutil/trace.go:171","msg":"trace[628245729] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"352.736342ms","start":"2026-05-26T16:43:25.770294Z","end":"2026-05-26T16:43:26.12303Z","steps":["trace[628245729] 'process raft request' (duration: 352.547198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:26.123164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.770279Z","time spent":"352.812294ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:43:26.123274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.838567ms","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-26T16:43:26.123303Z","caller":"traceutil/trace.go:171","msg":"trace[2090743154] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24578; }","duration":"351.017432ms","start":"2026-05-26T16:43:25.772278Z","end":"2026-05-26T16:43:26.123295Z","steps":["trace[2090743154] 'agreement among raft nodes before linearized reading' (duration: 350.777966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:26.12331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.973788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T16:43:26.123323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.772271Z","time spent":"351.047692ms","remote":"127.0.0.1:41324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-26T16:43:26.123385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.110406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:26.123418Z","caller":"traceutil/trace.go:171","msg":"trace[1772810326] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24578; }","duration":"105.176967ms","start":"2026-05-26T16:43:26.018232Z","end":"2026-05-26T16:43:26.123409Z","steps":["trace[1772810326] 'agreement among raft nodes before linearized reading' (duration: 105.128066ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:26.12334Z","caller":"traceutil/trace.go:171","msg":"trace[1545780556] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24578; }","duration":"322.003379ms","start":"2026-05-26T16:43:25.801326Z","end":"2026-05-26T16:43:26.123329Z","steps":["trace[1545780556] 'agreement among raft nodes before linearized reading' (duration: 321.954317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:26.123566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:25.80127Z","time spent":"322.289865ms","remote":"127.0.0.1:42570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-26T16:43:26.377463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.064534ms","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-26T16:43:26.377521Z","caller":"traceutil/trace.go:171","msg":"trace[352442662] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24578; }","duration":"162.168516ms","start":"2026-05-26T16:43:26.215339Z","end":"2026-05-26T16:43:26.377508Z","steps":["trace[352442662] 'range keys from in-memory index tree' (duration: 161.953422ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:26.377525Z","caller":"traceutil/trace.go:171","msg":"trace[284560414] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"129.699431ms","start":"2026-05-26T16:43:26.247795Z","end":"2026-05-26T16:43:26.377494Z","steps":["trace[284560414] 'process raft request' (duration: 118.142971ms)","trace[284560414] 'compare' (duration: 11.230702ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:28.664821Z","caller":"traceutil/trace.go:171","msg":"trace[217072950] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"110.401844ms","start":"2026-05-26T16:43:28.554398Z","end":"2026-05-26T16:43:28.664799Z","steps":["trace[217072950] 'process raft request' (duration: 110.24629ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:28.904546Z","caller":"traceutil/trace.go:171","msg":"trace[1829891846] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25461; }","duration":"131.381052ms","start":"2026-05-26T16:43:28.77314Z","end":"2026-05-26T16:43:28.904521Z","steps":["trace[1829891846] 'read index received' (duration: 131.122145ms)","trace[1829891846] 'applied index is now lower than readState.Index' (duration: 257.907µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:28.904741Z","caller":"traceutil/trace.go:171","msg":"trace[680760766] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"159.983545ms","start":"2026-05-26T16:43:28.744711Z","end":"2026-05-26T16:43:28.904695Z","steps":["trace[680760766] 'process raft request' (duration: 159.609105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:28.904762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.588548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:28.904908Z","caller":"traceutil/trace.go:171","msg":"trace[271072946] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24653; }","duration":"131.783431ms","start":"2026-05-26T16:43:28.773097Z","end":"2026-05-26T16:43:28.90488Z","steps":["trace[271072946] 'agreement among raft nodes before linearized reading' (duration: 131.522745ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:33.894227Z","caller":"traceutil/trace.go:171","msg":"trace[1227228385] transaction","detail":"{read_only:false; response_revision:24708; number_of_response:1; }","duration":"190.027302ms","start":"2026-05-26T16:43:33.704169Z","end":"2026-05-26T16:43:33.894196Z","steps":["trace[1227228385] 'process raft request' (duration: 189.858018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:34.09309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.069201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:34.093243Z","caller":"traceutil/trace.go:171","msg":"trace[900309830] transaction","detail":"{read_only:false; response_revision:24709; number_of_response:1; }","duration":"386.311915ms","start":"2026-05-26T16:43:33.706912Z","end":"2026-05-26T16:43:34.093224Z","steps":["trace[900309830] 'process raft request' (duration: 246.014399ms)","trace[900309830] 'compare' (duration: 139.954388ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:34.093311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:33.706874Z","time spent":"386.405827ms","remote":"127.0.0.1:41378","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-26T16:43:34.093506Z","caller":"traceutil/trace.go:171","msg":"trace[211468035] linearizableReadLoop","detail":"{readStateIndex:25521; appliedIndex:25517; }","duration":"295.653569ms","start":"2026-05-26T16:43:33.79783Z","end":"2026-05-26T16:43:34.093484Z","steps":["trace[211468035] 'read index received' (duration: 96.281602ms)","trace[211468035] 'applied index is now lower than readState.Index' (duration: 199.371057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:34.093754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.924347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-26T16:43:34.093798Z","caller":"traceutil/trace.go:171","msg":"trace[505381553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24711; }","duration":"296.027499ms","start":"2026-05-26T16:43:33.79776Z","end":"2026-05-26T16:43:34.093787Z","steps":["trace[505381553] 'agreement among raft nodes before linearized reading' (duration: 295.890195ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:34.093944Z","caller":"traceutil/trace.go:171","msg":"trace[780481738] transaction","detail":"{read_only:false; response_revision:24710; number_of_response:1; }","duration":"304.912924ms","start":"2026-05-26T16:43:33.789017Z","end":"2026-05-26T16:43:34.09393Z","steps":["trace[780481738] 'process raft request' (duration: 304.169046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:34.094053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:33.788986Z","time spent":"304.986205ms","remote":"127.0.0.1:41378","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-26T16:43:34.094087Z","caller":"traceutil/trace.go:171","msg":"trace[1351440652] transaction","detail":"{read_only:false; response_revision:24711; number_of_response:1; }","duration":"301.109042ms","start":"2026-05-26T16:43:33.792965Z","end":"2026-05-26T16:43:34.094074Z","steps":["trace[1351440652] 'process raft request' (duration: 300.452496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:34.094194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:33.792931Z","time spent":"301.194233ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:43:42.035552Z","caller":"traceutil/trace.go:171","msg":"trace[864653850] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"103.386434ms","start":"2026-05-26T16:43:41.932139Z","end":"2026-05-26T16:43:42.035526Z","steps":["trace[864653850] 'process raft request' (duration: 103.18266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.374321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.886039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:42.374384Z","caller":"traceutil/trace.go:171","msg":"trace[1278496327] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"271.679189ms","start":"2026-05-26T16:43:42.102694Z","end":"2026-05-26T16:43:42.374373Z","steps":["trace[1278496327] 'process raft request' (duration: 100.693548ms)","trace[1278496327] 'compare' (duration: 170.805447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:42.724527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.687696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:43:42.724719Z","caller":"traceutil/trace.go:171","msg":"trace[978740984] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"607.291107ms","start":"2026-05-26T16:43:42.117414Z","end":"2026-05-26T16:43:42.724705Z","steps":["trace[978740984] 'process raft request' (duration: 421.300174ms)","trace[978740984] 'compare' (duration: 185.401739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:43:42.724845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.117397Z","time spent":"607.41409ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:43:42.724905Z","caller":"traceutil/trace.go:171","msg":"trace[933904968] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"604.636102ms","start":"2026-05-26T16:43:42.120242Z","end":"2026-05-26T16:43:42.724878Z","steps":["trace[933904968] 'process raft request' (duration: 604.367316ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:42.724971Z","caller":"traceutil/trace.go:171","msg":"trace[607381187] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"601.410224ms","start":"2026-05-26T16:43:42.123553Z","end":"2026-05-26T16:43:42.724963Z","steps":["trace[607381187] 'process raft request' (duration: 601.304612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.725021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.123524Z","time spent":"601.471096ms","remote":"127.0.0.1:41378","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-26T16:43:42.725032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.120233Z","time spent":"604.732786ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T16:43:42.725132Z","caller":"traceutil/trace.go:171","msg":"trace[146576510] linearizableReadLoop","detail":"{readStateIndex:25590; appliedIndex:25587; }","duration":"521.060398ms","start":"2026-05-26T16:43:42.204066Z","end":"2026-05-26T16:43:42.725126Z","steps":["trace[146576510] 'read index received' (duration: 334.729437ms)","trace[146576510] 'applied index is now lower than readState.Index' (duration: 186.329611ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:43:42.725171Z","caller":"traceutil/trace.go:171","msg":"trace[2036660287] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"235.061953ms","start":"2026-05-26T16:43:42.490081Z","end":"2026-05-26T16:43:42.725143Z","steps":["trace[2036660287] 'process raft request' (duration: 234.876428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.725225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.169522ms","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-26T16:43:42.725243Z","caller":"traceutil/trace.go:171","msg":"trace[1296971723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24780; }","duration":"521.220493ms","start":"2026-05-26T16:43:42.204017Z","end":"2026-05-26T16:43:42.725238Z","steps":["trace[1296971723] 'agreement among raft nodes before linearized reading' (duration: 521.145361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.725257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.203998Z","time spent":"521.255783ms","remote":"127.0.0.1:41378","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-26T16:43:42.735783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.633249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:43:42.735813Z","caller":"traceutil/trace.go:171","msg":"trace[287391638] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:24781; }","duration":"290.70055ms","start":"2026-05-26T16:43:42.445105Z","end":"2026-05-26T16:43:42.735805Z","steps":["trace[287391638] 'agreement among raft nodes before linearized reading' (duration: 290.652869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.735817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.910225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-05-26T16:43:42.735837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.948946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T16:43:42.735863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.098631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-26T16:43:42.735897Z","caller":"traceutil/trace.go:171","msg":"trace[1907602899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24781; }","duration":"369.044097ms","start":"2026-05-26T16:43:42.36684Z","end":"2026-05-26T16:43:42.735885Z","steps":["trace[1907602899] 'agreement among raft nodes before linearized reading' (duration: 368.932365ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:42.735926Z","caller":"traceutil/trace.go:171","msg":"trace[1821909532] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24781; }","duration":"360.185964ms","start":"2026-05-26T16:43:42.375725Z","end":"2026-05-26T16:43:42.735911Z","steps":["trace[1821909532] 'agreement among raft nodes before linearized reading' (duration: 359.982619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.73594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.180351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"warn","ts":"2026-05-26T16:43:42.735963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.375719Z","time spent":"360.236765ms","remote":"127.0.0.1:41306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-26T16:43:42.736004Z","caller":"traceutil/trace.go:171","msg":"trace[1080533502] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24781; }","duration":"369.275993ms","start":"2026-05-26T16:43:42.366716Z","end":"2026-05-26T16:43:42.735992Z","steps":["trace[1080533502] 'agreement among raft nodes before linearized reading' (duration: 369.100419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.735934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.366826Z","time spent":"369.103939ms","remote":"127.0.0.1:41168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T16:43:42.73586Z","caller":"traceutil/trace.go:171","msg":"trace[218265702] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24781; }","duration":"226.980756ms","start":"2026-05-26T16:43:42.508868Z","end":"2026-05-26T16:43:42.735849Z","steps":["trace[218265702] 'agreement among raft nodes before linearized reading' (duration: 226.865044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.736034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.366699Z","time spent":"369.329415ms","remote":"127.0.0.1:42570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3259,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-26T16:43:42.736303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.901682ms","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-26T16:43:42.73634Z","caller":"traceutil/trace.go:171","msg":"trace[863686373] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24781; }","duration":"517.974184ms","start":"2026-05-26T16:43:42.218357Z","end":"2026-05-26T16:43:42.736331Z","steps":["trace[863686373] 'agreement among raft nodes before linearized reading' (duration: 517.321668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:43:42.736365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:43:42.218337Z","time spent":"518.019056ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-26T16:43:48.249081Z","caller":"traceutil/trace.go:171","msg":"trace[1215050709] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"195.108936ms","start":"2026-05-26T16:43:48.053944Z","end":"2026-05-26T16:43:48.249053Z","steps":["trace[1215050709] 'process raft request' (duration: 194.910821ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:48.294928Z","caller":"traceutil/trace.go:171","msg":"trace[78312240] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"218.02198ms","start":"2026-05-26T16:43:48.076884Z","end":"2026-05-26T16:43:48.294906Z","steps":["trace[78312240] 'process raft request' (duration: 216.039193ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:43:49.11999Z","caller":"traceutil/trace.go:171","msg":"trace[1503634522] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"121.733339ms","start":"2026-05-26T16:43:48.998231Z","end":"2026-05-26T16:43:49.119964Z","steps":["trace[1503634522] 'process raft request' (duration: 121.580315ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:00.986232Z","caller":"traceutil/trace.go:171","msg":"trace[596582197] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"204.168614ms","start":"2026-05-26T16:44:00.782032Z","end":"2026-05-26T16:44:00.9862Z","steps":["trace[596582197] 'process raft request' (duration: 203.945069ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.017801Z","caller":"traceutil/trace.go:171","msg":"trace[384143401] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"233.262229ms","start":"2026-05-26T16:44:00.784518Z","end":"2026-05-26T16:44:01.01778Z","steps":["trace[384143401] 'process raft request' (duration: 233.182697ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.017879Z","caller":"traceutil/trace.go:171","msg":"trace[1744082116] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"234.9488ms","start":"2026-05-26T16:44:00.782893Z","end":"2026-05-26T16:44:01.017842Z","steps":["trace[1744082116] 'process raft request' (duration: 234.673734ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.018206Z","caller":"traceutil/trace.go:171","msg":"trace[237891217] linearizableReadLoop","detail":"{readStateIndex:25745; appliedIndex:25741; }","duration":"212.208579ms","start":"2026-05-26T16:44:00.80597Z","end":"2026-05-26T16:44:01.018178Z","steps":["trace[237891217] 'read index received' (duration: 180.266426ms)","trace[237891217] 'applied index is now lower than readState.Index' (duration: 31.941023ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:01.018259Z","caller":"traceutil/trace.go:171","msg":"trace[894505484] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"227.571071ms","start":"2026-05-26T16:44:00.790675Z","end":"2026-05-26T16:44:01.018246Z","steps":["trace[894505484] 'process raft request' (duration: 227.375006ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.018226Z","caller":"traceutil/trace.go:171","msg":"trace[2115709325] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"229.291703ms","start":"2026-05-26T16:44:00.788883Z","end":"2026-05-26T16:44:01.018174Z","steps":["trace[2115709325] 'process raft request' (duration: 228.860193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.018392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.784404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T16:44:01.018416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.433504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-26T16:44:01.018436Z","caller":"traceutil/trace.go:171","msg":"trace[1807179356] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:24929; }","duration":"210.874486ms","start":"2026-05-26T16:44:00.807546Z","end":"2026-05-26T16:44:01.018421Z","steps":["trace[1807179356] 'agreement among raft nodes before linearized reading' (duration: 210.772194ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.018463Z","caller":"traceutil/trace.go:171","msg":"trace[146676289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24929; }","duration":"212.527167ms","start":"2026-05-26T16:44:00.805921Z","end":"2026-05-26T16:44:01.018449Z","steps":["trace[146676289] 'agreement among raft nodes before linearized reading' (duration: 212.342542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.01847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.275445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T16:44:01.018508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.916856ms","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-26T16:44:01.018535Z","caller":"traceutil/trace.go:171","msg":"trace[528979319] 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:24929; }","duration":"104.400888ms","start":"2026-05-26T16:44:00.914118Z","end":"2026-05-26T16:44:01.018519Z","steps":["trace[528979319] 'agreement among raft nodes before linearized reading' (duration: 104.257825ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.018549Z","caller":"traceutil/trace.go:171","msg":"trace[938536293] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24929; }","duration":"201.021439ms","start":"2026-05-26T16:44:00.817515Z","end":"2026-05-26T16:44:01.018536Z","steps":["trace[938536293] 'agreement among raft nodes before linearized reading' (duration: 200.855235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.311393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.361153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:44:01.311508Z","caller":"traceutil/trace.go:171","msg":"trace[1750254726] linearizableReadLoop","detail":"{readStateIndex:25746; appliedIndex:25745; }","duration":"288.545638ms","start":"2026-05-26T16:44:01.022941Z","end":"2026-05-26T16:44:01.311487Z","steps":["trace[1750254726] 'read index received' (duration: 140.017581ms)","trace[1750254726] 'applied index is now lower than readState.Index' (duration: 148.526097ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:01.31179Z","caller":"traceutil/trace.go:171","msg":"trace[569048471] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"289.471229ms","start":"2026-05-26T16:44:01.022298Z","end":"2026-05-26T16:44:01.311769Z","steps":["trace[569048471] 'process raft request' (duration: 140.645905ms)","trace[569048471] 'compare' (duration: 147.966164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T16:44:01.312049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.097881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:44:01.312109Z","caller":"traceutil/trace.go:171","msg":"trace[230965345] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24930; }","duration":"289.201903ms","start":"2026-05-26T16:44:01.022892Z","end":"2026-05-26T16:44:01.312094Z","steps":["trace[230965345] 'agreement among raft nodes before linearized reading' (duration: 289.108791ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.313449Z","caller":"traceutil/trace.go:171","msg":"trace[1944438080] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"289.549431ms","start":"2026-05-26T16:44:01.023891Z","end":"2026-05-26T16:44:01.313441Z","steps":["trace[1944438080] 'process raft request' (duration: 289.48707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.385235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.148756ms","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-26T16:44:01.385315Z","caller":"traceutil/trace.go:171","msg":"trace[1175812435] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24932; }","duration":"214.292869ms","start":"2026-05-26T16:44:01.171003Z","end":"2026-05-26T16:44:01.385296Z","steps":["trace[1175812435] 'agreement among raft nodes before linearized reading' (duration: 214.139225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.385432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.424259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T16:44:01.385494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.994691ms","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-26T16:44:01.385531Z","caller":"traceutil/trace.go:171","msg":"trace[935099525] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24932; }","duration":"282.539942ms","start":"2026-05-26T16:44:01.102965Z","end":"2026-05-26T16:44:01.385505Z","steps":["trace[935099525] 'agreement among raft nodes before linearized reading' (duration: 282.357458ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.385577Z","caller":"traceutil/trace.go:171","msg":"trace[497071583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24932; }","duration":"204.117343ms","start":"2026-05-26T16:44:01.181438Z","end":"2026-05-26T16:44:01.385555Z","steps":["trace[497071583] 'agreement among raft nodes before linearized reading' (duration: 203.851037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.716672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.126721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:44:01.716998Z","caller":"traceutil/trace.go:171","msg":"trace[1972042097] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"315.184363ms","start":"2026-05-26T16:44:01.401791Z","end":"2026-05-26T16:44:01.716976Z","steps":["trace[1972042097] 'process raft request' (duration: 315.120992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.717095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:44:01.401782Z","time spent":"315.265276ms","remote":"127.0.0.1:41378","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-26T16:44:01.717122Z","caller":"traceutil/trace.go:171","msg":"trace[1401927257] transaction","detail":"{read_only:false; response_revision:24933; number_of_response:1; }","duration":"325.827621ms","start":"2026-05-26T16:44:01.391268Z","end":"2026-05-26T16:44:01.717096Z","steps":["trace[1401927257] 'process raft request' (duration: 41.196177ms)","trace[1401927257] 'compare' (duration: 283.710191ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:01.717155Z","caller":"traceutil/trace.go:171","msg":"trace[457497614] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"315.830819ms","start":"2026-05-26T16:44:01.401307Z","end":"2026-05-26T16:44:01.717138Z","steps":["trace[457497614] 'process raft request' (duration: 315.544051ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:01.717146Z","caller":"traceutil/trace.go:171","msg":"trace[1729557982] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"325.302809ms","start":"2026-05-26T16:44:01.391818Z","end":"2026-05-26T16:44:01.71712Z","steps":["trace[1729557982] 'process raft request' (duration: 324.954049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:01.717211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:44:01.401291Z","time spent":"315.89178ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T16:44:01.717233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:44:01.391243Z","time spent":"325.927294ms","remote":"127.0.0.1:41378","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-26T16:44:01.71724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T16:44:01.391797Z","time spent":"325.393261ms","remote":"127.0.0.1:41378","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-26T16:44:02.102342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.119346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T16:44:02.102396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.06588ms","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-26T16:44:02.102425Z","caller":"traceutil/trace.go:171","msg":"trace[1794548138] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24936; }","duration":"186.24758ms","start":"2026-05-26T16:44:01.916159Z","end":"2026-05-26T16:44:02.102406Z","steps":["trace[1794548138] 'count revisions from in-memory index tree' (duration: 186.011355ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:02.102718Z","caller":"traceutil/trace.go:171","msg":"trace[335730018] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24936; }","duration":"161.148732ms","start":"2026-05-26T16:44:01.9413Z","end":"2026-05-26T16:44:02.102449Z","steps":["trace[335730018] 'range keys from in-memory index tree' (duration: 160.907516ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:14.063497Z","caller":"traceutil/trace.go:171","msg":"trace[1158387264] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"247.39502ms","start":"2026-05-26T16:44:13.816074Z","end":"2026-05-26T16:44:14.063469Z","steps":["trace[1158387264] 'process raft request' (duration: 199.720626ms)","trace[1158387264] 'compare' (duration: 47.296485ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:14.063845Z","caller":"traceutil/trace.go:171","msg":"trace[1992264721] linearizableReadLoop","detail":"{readStateIndex:25847; appliedIndex:25844; }","duration":"176.046453ms","start":"2026-05-26T16:44:13.887782Z","end":"2026-05-26T16:44:14.063828Z","steps":["trace[1992264721] 'read index received' (duration: 127.982179ms)","trace[1992264721] 'applied index is now lower than readState.Index' (duration: 48.063554ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:14.063987Z","caller":"traceutil/trace.go:171","msg":"trace[1257877002] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"247.8077ms","start":"2026-05-26T16:44:13.816164Z","end":"2026-05-26T16:44:14.063971Z","steps":["trace[1257877002] 'process raft request' (duration: 247.220266ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:14.064093Z","caller":"traceutil/trace.go:171","msg":"trace[1425996888] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"246.745666ms","start":"2026-05-26T16:44:13.817319Z","end":"2026-05-26T16:44:14.064065Z","steps":["trace[1425996888] 'process raft request' (duration: 246.459839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:14.064624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.277649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T16:44:14.064666Z","caller":"traceutil/trace.go:171","msg":"trace[2064673701] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25028; }","duration":"176.925475ms","start":"2026-05-26T16:44:13.887728Z","end":"2026-05-26T16:44:14.064654Z","steps":["trace[2064673701] 'agreement among raft nodes before linearized reading' (duration: 176.278489ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:18.512348Z","caller":"traceutil/trace.go:171","msg":"trace[709245389] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"100.456843ms","start":"2026-05-26T16:44:18.411859Z","end":"2026-05-26T16:44:18.512316Z","steps":["trace[709245389] 'process raft request' (duration: 90.134742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T16:44:23.969388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.998585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T16:44:23.969708Z","caller":"traceutil/trace.go:171","msg":"trace[162494559] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"151.314024ms","start":"2026-05-26T16:44:23.818351Z","end":"2026-05-26T16:44:23.969665Z","steps":["trace[162494559] 'process raft request' (duration: 23.95352ms)","trace[162494559] 'compare' (duration: 126.863652ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:24.335141Z","caller":"traceutil/trace.go:171","msg":"trace[1884077003] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"183.833061ms","start":"2026-05-26T16:44:24.151242Z","end":"2026-05-26T16:44:24.335075Z","steps":["trace[1884077003] 'process raft request' (duration: 113.455457ms)","trace[1884077003] 'compare' (duration: 69.740439ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:24.335191Z","caller":"traceutil/trace.go:171","msg":"trace[256353944] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"158.673672ms","start":"2026-05-26T16:44:24.17644Z","end":"2026-05-26T16:44:24.335113Z","steps":["trace[256353944] 'process raft request' (duration: 158.540129ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:24.335524Z","caller":"traceutil/trace.go:171","msg":"trace[1200780559] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"184.032387ms","start":"2026-05-26T16:44:24.151459Z","end":"2026-05-26T16:44:24.335492Z","steps":["trace[1200780559] 'process raft request' (duration: 183.337729ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:25.250989Z","caller":"traceutil/trace.go:171","msg":"trace[1779833691] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"148.340443ms","start":"2026-05-26T16:44:25.102624Z","end":"2026-05-26T16:44:25.250965Z","steps":["trace[1779833691] 'process raft request' (duration: 148.300032ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:25.251065Z","caller":"traceutil/trace.go:171","msg":"trace[80928270] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"150.31033ms","start":"2026-05-26T16:44:25.100715Z","end":"2026-05-26T16:44:25.251026Z","steps":["trace[80928270] 'process raft request' (duration: 150.166467ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:25.25105Z","caller":"traceutil/trace.go:171","msg":"trace[941891060] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"153.347353ms","start":"2026-05-26T16:44:25.097662Z","end":"2026-05-26T16:44:25.25101Z","steps":["trace[941891060] 'process raft request' (duration: 153.131068ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:25.251151Z","caller":"traceutil/trace.go:171","msg":"trace[349910904] transaction","detail":"{read_only:false; response_revision:25116; number_of_response:1; }","duration":"155.016584ms","start":"2026-05-26T16:44:25.095994Z","end":"2026-05-26T16:44:25.25101Z","steps":["trace[349910904] 'process raft request' (duration: 154.376638ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:44:25.688561Z","caller":"traceutil/trace.go:171","msg":"trace[808230058] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"100.025822ms","start":"2026-05-26T16:44:25.588387Z","end":"2026-05-26T16:44:25.688413Z","steps":["trace[808230058] 'process raft request' (duration: 76.675916ms)","trace[808230058] 'compare' (duration: 23.11472ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T16:44:25.967524Z","caller":"traceutil/trace.go:171","msg":"trace[241938356] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"141.494227ms","start":"2026-05-26T16:44:25.826004Z","end":"2026-05-26T16:44:25.967498Z","steps":["trace[241938356] 'process raft request' (duration: 141.332763ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T16:45:59.144234Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23214} {"level":"info","ts":"2026-05-26T16:45:59.273298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23214,"took":"124.73298ms","hash":2202713879,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":36126720,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-26T16:45:59.273366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2202713879,"revision":23214,"compact-revision":20021} {"level":"info","ts":"2026-05-26T16:46:00.588512Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000693a.wal"} {"level":"info","ts":"2026-05-26T16:50:59.150332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26072} {"level":"info","ts":"2026-05-26T16:50:59.295341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26072,"took":"140.730181ms","hash":1382171610,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":33591296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T16:50:59.295413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1382171610,"revision":26072,"compact-revision":23214} {"level":"info","ts":"2026-05-26T16:51:50.253268Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7b89c171c5fe95c","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T16:51:50.25948Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-26T16:51:50.259647Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-26T16:55:59.157245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28705} {"level":"info","ts":"2026-05-26T16:55:59.290444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28705,"took":"128.462403ms","hash":3477454836,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":30195712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-26T16:55:59.290512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3477454836,"revision":28705,"compact-revision":26072} {"level":"info","ts":"2026-05-26T17:00:59.164063Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30923} {"level":"info","ts":"2026-05-26T17:00:59.28007Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30923,"took":"111.990663ms","hash":2670095965,"current-db-size-bytes":58798080,"current-db-size":"59 MB","current-db-size-in-use-bytes":31617024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T17:00:59.280144Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2670095965,"revision":30923,"compact-revision":28705} {"level":"info","ts":"2026-05-26T17:05:59.16941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33258} {"level":"info","ts":"2026-05-26T17:05:59.32036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33258,"took":"146.57123ms","hash":2728560238,"current-db-size-bytes":59842560,"current-db-size":"60 MB","current-db-size-in-use-bytes":32530432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T17:05:59.320415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2728560238,"revision":33258,"compact-revision":30923} {"level":"info","ts":"2026-05-26T17:10:59.174657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35580} {"level":"info","ts":"2026-05-26T17:10:59.318966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35580,"took":"139.720473ms","hash":2373407515,"current-db-size-bytes":61550592,"current-db-size":"62 MB","current-db-size-in-use-bytes":31690752,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T17:10:59.319023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2373407515,"revision":35580,"compact-revision":33258} {"level":"info","ts":"2026-05-26T17:13:06.691659Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b7b89c171c5fe95c","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T17:13:06.697171Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-26T17:13:06.697272Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}