{"level":"warn","ts":"2026-04-28T22:27:09.282573Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T22:27:09.28267Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-04-28T22:27:09.282731Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T22:27:09.282739Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-28T22:27:09.282755Z","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-04-28T22:27:09.283363Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-28T22:27:09.283432Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-04-28T22:27:09.287701Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.060562ms"} {"level":"info","ts":"2026-04-28T22:27:09.295837Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-28T22:27:09.295924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T22:27:09.295948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-28T22:27:09.295963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T22:27:09.295975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-28T22:27:09.29603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-28T22:27:09.305404Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T22:27:09.30722Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T22:27:09.309468Z","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-04-28T22:27:09.311626Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T22:27:09.311835Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T22:27:09.311977Z","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-04-28T22:27:09.312552Z","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-04-28T22:27:09.312586Z","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-04-28T22:27:09.314552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-28T22:27:09.31697Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-28T22:27:09.31916Z","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-04-28T22:27:09.319276Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-28T22:27:09.319311Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-28T22:27:09.319615Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T22:27:09.319689Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T22:27:09.997206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T22:27:09.997283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T22:27:09.997359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-28T22:27:09.997393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-28T22:27:09.997406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-28T22:27:09.997429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-28T22:27:09.997444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-28T22:27:09.998679Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:27:09.999442Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T22:27:09.999538Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T22:27:10.000154Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:27:10.000327Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:27:10.000383Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T22:27:10.002627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T22:27:10.002947Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-28T22:27:10.005364Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T22:27:10.013793Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T22:27:10.013817Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T22:27:25.943438Z","caller":"traceutil/trace.go:171","msg":"trace[422721555] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"146.941012ms","start":"2026-04-28T22:27:25.796469Z","end":"2026-04-28T22:27:25.94341Z","steps":["trace[422721555] 'read index received' (duration: 146.695828ms)","trace[422721555] 'applied index is now lower than readState.Index' (duration: 244.284µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:27:25.943527Z","caller":"traceutil/trace.go:171","msg":"trace[1112916562] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"198.432679ms","start":"2026-04-28T22:27:25.745052Z","end":"2026-04-28T22:27:25.943484Z","steps":["trace[1112916562] 'process raft request' (duration: 198.193464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:25.943661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.130747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T22:27:25.943728Z","caller":"traceutil/trace.go:171","msg":"trace[1572186611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:368; }","duration":"147.29113ms","start":"2026-04-28T22:27:25.796425Z","end":"2026-04-28T22:27:25.943716Z","steps":["trace[1572186611] 'agreement among raft nodes before linearized reading' (duration: 147.119966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:26.249094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.951973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:27:26.249298Z","caller":"traceutil/trace.go:171","msg":"trace[782154673] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"300.109963ms","start":"2026-04-28T22:27:25.949143Z","end":"2026-04-28T22:27:26.249253Z","steps":["trace[782154673] 'process raft request' (duration: 124.397695ms)","trace[782154673] 'compare' (duration: 174.577685ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:27:26.249316Z","caller":"traceutil/trace.go:171","msg":"trace[648117529] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"299.771236ms","start":"2026-04-28T22:27:25.949528Z","end":"2026-04-28T22:27:26.249299Z","steps":["trace[648117529] 'process raft request' (duration: 299.673904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:26.249409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:27:25.949111Z","time spent":"300.244866ms","remote":"127.0.0.1:36614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:27:26.548189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.245114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:27:26.548333Z","caller":"traceutil/trace.go:171","msg":"trace[1127519662] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"294.765794ms","start":"2026-04-28T22:27:26.253543Z","end":"2026-04-28T22:27:26.548309Z","steps":["trace[1127519662] 'process raft request' (duration: 131.308136ms)","trace[1127519662] 'compare' (duration: 163.009889ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:27:26.549452Z","caller":"traceutil/trace.go:171","msg":"trace[2083238913] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:384; }","duration":"120.949465ms","start":"2026-04-28T22:27:26.428481Z","end":"2026-04-28T22:27:26.549431Z","steps":["trace[2083238913] 'read index received' (duration: 120.751241ms)","trace[2083238913] 'applied index is now lower than readState.Index' (duration: 197.194µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:27:26.549519Z","caller":"traceutil/trace.go:171","msg":"trace[1175249968] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"293.0795ms","start":"2026-04-28T22:27:26.256418Z","end":"2026-04-28T22:27:26.549497Z","steps":["trace[1175249968] 'process raft request' (duration: 292.946757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:26.549641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.14423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:27:26.549735Z","caller":"traceutil/trace.go:171","msg":"trace[414168008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"121.289292ms","start":"2026-04-28T22:27:26.428425Z","end":"2026-04-28T22:27:26.549714Z","steps":["trace[414168008] 'agreement among raft nodes before linearized reading' (duration: 121.093048ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:26.549771Z","caller":"traceutil/trace.go:171","msg":"trace[942164354] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"294.762994ms","start":"2026-04-28T22:27:26.254673Z","end":"2026-04-28T22:27:26.549436Z","steps":["trace[942164354] 'process raft request' (duration: 294.579971ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:45.477085Z","caller":"traceutil/trace.go:171","msg":"trace[737046897] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"256.133706ms","start":"2026-04-28T22:27:45.220931Z","end":"2026-04-28T22:27:45.477064Z","steps":["trace[737046897] 'read index received' (duration: 255.539724ms)","trace[737046897] 'applied index is now lower than readState.Index' (duration: 593.181µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:27:45.477253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.302789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T22:27:45.479202Z","caller":"traceutil/trace.go:171","msg":"trace[721617653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"258.272049ms","start":"2026-04-28T22:27:45.220884Z","end":"2026-04-28T22:27:45.479157Z","steps":["trace[721617653] 'agreement among raft nodes before linearized reading' (duration: 256.268238ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:45.477218Z","caller":"traceutil/trace.go:171","msg":"trace[1998833117] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"261.388241ms","start":"2026-04-28T22:27:45.215761Z","end":"2026-04-28T22:27:45.477149Z","steps":["trace[1998833117] 'process raft request' (duration: 260.846ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:45.485807Z","caller":"traceutil/trace.go:171","msg":"trace[1619421209] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"169.492687ms","start":"2026-04-28T22:27:45.316281Z","end":"2026-04-28T22:27:45.485774Z","steps":["trace[1619421209] 'process raft request' (duration: 169.327354ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:27:45.912344Z","caller":"traceutil/trace.go:171","msg":"trace[1141891126] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"145.829078ms","start":"2026-04-28T22:27:45.766486Z","end":"2026-04-28T22:27:45.912315Z","steps":["trace[1141891126] 'read index received' (duration: 145.626964ms)","trace[1141891126] 'applied index is now lower than readState.Index' (duration: 200.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:27:45.9124Z","caller":"traceutil/trace.go:171","msg":"trace[1551648204] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"370.880702ms","start":"2026-04-28T22:27:45.541492Z","end":"2026-04-28T22:27:45.912373Z","steps":["trace[1551648204] 'process raft request' (duration: 370.675478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:45.912571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T22:27:45.541468Z","time spent":"370.992195ms","remote":"127.0.0.1:36644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T22:27:45.912769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.256026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vxm5j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T22:27:45.912885Z","caller":"traceutil/trace.go:171","msg":"trace[183224566] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vxm5j; range_end:; response_count:1; response_revision:574; }","duration":"146.42723ms","start":"2026-04-28T22:27:45.766431Z","end":"2026-04-28T22:27:45.912858Z","steps":["trace[183224566] 'agreement among raft nodes before linearized reading' (duration: 146.017891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:45.91289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.326755ms","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-04-28T22:27:45.912987Z","caller":"traceutil/trace.go:171","msg":"trace[1566844868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"106.471321ms","start":"2026-04-28T22:27:45.806492Z","end":"2026-04-28T22:27:45.912963Z","steps":["trace[1566844868] 'agreement among raft nodes before linearized reading' (duration: 106.088822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:27:45.913116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.294317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T22:27:45.913201Z","caller":"traceutil/trace.go:171","msg":"trace[2054023042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"107.43704ms","start":"2026-04-28T22:27:45.805748Z","end":"2026-04-28T22:27:45.913185Z","steps":["trace[2054023042] 'agreement among raft nodes before linearized reading' (duration: 106.714775ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:28:35.675201Z","caller":"traceutil/trace.go:171","msg":"trace[1556756862] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"102.814226ms","start":"2026-04-28T22:28:35.572362Z","end":"2026-04-28T22:28:35.675176Z","steps":["trace[1556756862] 'process raft request' (duration: 102.660423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:29:22.889994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.469523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T22:29:22.890152Z","caller":"traceutil/trace.go:171","msg":"trace[1699051585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1795; }","duration":"292.662556ms","start":"2026-04-28T22:29:22.597431Z","end":"2026-04-28T22:29:22.890094Z","steps":["trace[1699051585] 'range keys from in-memory index tree' (duration: 292.257278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:29:22.890151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.581514ms","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-04-28T22:29:22.890366Z","caller":"traceutil/trace.go:171","msg":"trace[325420914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1795; }","duration":"295.85911ms","start":"2026-04-28T22:29:22.594486Z","end":"2026-04-28T22:29:22.890345Z","steps":["trace[325420914] 'range keys from in-memory index tree' (duration: 295.342949ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:29:23.090123Z","caller":"traceutil/trace.go:171","msg":"trace[145424672] linearizableReadLoop","detail":"{readStateIndex:1851; appliedIndex:1849; }","duration":"167.36099ms","start":"2026-04-28T22:29:22.922729Z","end":"2026-04-28T22:29:23.09009Z","steps":["trace[145424672] 'read index received' (duration: 52.223186ms)","trace[145424672] 'applied index is now lower than readState.Index' (duration: 115.136834ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:29:23.090134Z","caller":"traceutil/trace.go:171","msg":"trace[856890895] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"194.99272ms","start":"2026-04-28T22:29:22.895109Z","end":"2026-04-28T22:29:23.090102Z","steps":["trace[856890895] 'process raft request' (duration: 105.601736ms)","trace[856890895] 'compare' (duration: 89.20201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:29:23.090374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.631716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T22:29:23.090417Z","caller":"traceutil/trace.go:171","msg":"trace[323942229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1797; }","duration":"167.735798ms","start":"2026-04-28T22:29:22.922671Z","end":"2026-04-28T22:29:23.090407Z","steps":["trace[323942229] 'agreement among raft nodes before linearized reading' (duration: 167.572565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:30:04.644692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.778335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T22:30:04.644828Z","caller":"traceutil/trace.go:171","msg":"trace[1995047610] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"227.758945ms","start":"2026-04-28T22:30:04.417048Z","end":"2026-04-28T22:30:04.644807Z","steps":["trace[1995047610] 'read index received' (duration: 101.424409ms)","trace[1995047610] 'applied index is now lower than readState.Index' (duration: 126.333035ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:30:04.645104Z","caller":"traceutil/trace.go:171","msg":"trace[1747327738] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"242.036199ms","start":"2026-04-28T22:30:04.403018Z","end":"2026-04-28T22:30:04.645054Z","steps":["trace[1747327738] 'process raft request' (duration: 115.468928ms)","trace[1747327738] 'compare' (duration: 125.610022ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:30:04.645184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.123472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-28T22:30:04.645411Z","caller":"traceutil/trace.go:171","msg":"trace[1398501165] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"228.375297ms","start":"2026-04-28T22:30:04.417016Z","end":"2026-04-28T22:30:04.645391Z","steps":["trace[1398501165] 'agreement among raft nodes before linearized reading' (duration: 227.849146ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:30:04.812041Z","caller":"traceutil/trace.go:171","msg":"trace[1737357970] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"109.206127ms","start":"2026-04-28T22:30:04.702804Z","end":"2026-04-28T22:30:04.81201Z","steps":["trace[1737357970] 'process raft request' (duration: 79.674081ms)","trace[1737357970] 'compare' (duration: 29.260321ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:31:16.691702Z","caller":"traceutil/trace.go:171","msg":"trace[1316547437] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"186.671625ms","start":"2026-04-28T22:31:16.505006Z","end":"2026-04-28T22:31:16.691677Z","steps":["trace[1316547437] 'process raft request' (duration: 186.506372ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:31:16.815085Z","caller":"traceutil/trace.go:171","msg":"trace[1291481848] linearizableReadLoop","detail":"{readStateIndex:2795; appliedIndex:2794; }","duration":"113.549404ms","start":"2026-04-28T22:31:16.701493Z","end":"2026-04-28T22:31:16.815043Z","steps":["trace[1291481848] 'read index received' (duration: 98.549743ms)","trace[1291481848] 'applied index is now lower than readState.Index' (duration: 14.998501ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:31:16.815214Z","caller":"traceutil/trace.go:171","msg":"trace[460417628] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"116.093255ms","start":"2026-04-28T22:31:16.699075Z","end":"2026-04-28T22:31:16.815169Z","steps":["trace[460417628] 'process raft request' (duration: 101.019032ms)","trace[460417628] 'compare' (duration: 14.814918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:31:16.815559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.035304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:5908"} {"level":"info","ts":"2026-04-28T22:31:16.815638Z","caller":"traceutil/trace.go:171","msg":"trace[1760644383] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2689; }","duration":"114.142206ms","start":"2026-04-28T22:31:16.701478Z","end":"2026-04-28T22:31:16.81562Z","steps":["trace[1760644383] 'agreement among raft nodes before linearized reading' (duration: 113.823ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:33:31.90817Z","caller":"traceutil/trace.go:171","msg":"trace[1708944021] transaction","detail":"{read_only:false; response_revision:4390; number_of_response:1; }","duration":"215.552169ms","start":"2026-04-28T22:33:31.692207Z","end":"2026-04-28T22:33:31.90776Z","steps":["trace[1708944021] 'process raft request' (duration: 214.962488ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:33:47.663383Z","caller":"traceutil/trace.go:171","msg":"trace[569205000] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4959; }","duration":"194.314464ms","start":"2026-04-28T22:33:47.469042Z","end":"2026-04-28T22:33:47.663357Z","steps":["trace[569205000] 'read index received' (duration: 194.062169ms)","trace[569205000] 'applied index is now lower than readState.Index' (duration: 251.285µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:33:47.663674Z","caller":"traceutil/trace.go:171","msg":"trace[873724397] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"246.599765ms","start":"2026-04-28T22:33:47.417062Z","end":"2026-04-28T22:33:47.663662Z","steps":["trace[873724397] 'process raft request' (duration: 246.131166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:33:47.663906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.845828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T22:33:47.664014Z","caller":"traceutil/trace.go:171","msg":"trace[1649003558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4762; }","duration":"188.009921ms","start":"2026-04-28T22:33:47.475983Z","end":"2026-04-28T22:33:47.663993Z","steps":["trace[1649003558] 'agreement among raft nodes before linearized reading' (duration: 187.710425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:33:47.664054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.991377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6s2v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-28T22:33:47.664125Z","caller":"traceutil/trace.go:171","msg":"trace[936423567] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6s2v5; range_end:; response_count:1; response_revision:4762; }","duration":"195.099278ms","start":"2026-04-28T22:33:47.469007Z","end":"2026-04-28T22:33:47.664106Z","steps":["trace[936423567] 'agreement among raft nodes before linearized reading' (duration: 194.840773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:33:47.664109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.054423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T22:33:47.664191Z","caller":"traceutil/trace.go:171","msg":"trace[1736806701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4762; }","duration":"164.180044ms","start":"2026-04-28T22:33:47.499992Z","end":"2026-04-28T22:33:47.664172Z","steps":["trace[1736806701] 'agreement among raft nodes before linearized reading' (duration: 163.908459ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:33:54.507814Z","caller":"traceutil/trace.go:171","msg":"trace[1756833304] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"122.739276ms","start":"2026-04-28T22:33:54.385044Z","end":"2026-04-28T22:33:54.507783Z","steps":["trace[1756833304] 'process raft request' (duration: 121.965231ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:33:54.508024Z","caller":"traceutil/trace.go:171","msg":"trace[363020266] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"121.592034ms","start":"2026-04-28T22:33:54.386259Z","end":"2026-04-28T22:33:54.507851Z","steps":["trace[363020266] 'process raft request' (duration: 121.459362ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:34:05.0708Z","caller":"traceutil/trace.go:171","msg":"trace[718421028] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5077; }","duration":"108.332065ms","start":"2026-04-28T22:34:04.96244Z","end":"2026-04-28T22:34:05.070772Z","steps":["trace[718421028] 'process raft request' (duration: 87.252114ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:34:05.185598Z","caller":"traceutil/trace.go:171","msg":"trace[143128433] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"102.771627ms","start":"2026-04-28T22:34:05.082803Z","end":"2026-04-28T22:34:05.185574Z","steps":["trace[143128433] 'process raft request' (duration: 102.602763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:34:05.887416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.180353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-28T22:34:05.88752Z","caller":"traceutil/trace.go:171","msg":"trace[83602953] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5079; }","duration":"106.323506ms","start":"2026-04-28T22:34:05.781172Z","end":"2026-04-28T22:34:05.887495Z","steps":["trace[83602953] 'range keys from in-memory index tree' (duration: 106.00002ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:37:10.025862Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3223} {"level":"info","ts":"2026-04-28T22:37:10.11608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3223,"took":"88.040751ms","hash":1157345558,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":34041856,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-28T22:37:10.116149Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1157345558,"revision":3223,"compact-revision":-1} {"level":"info","ts":"2026-04-28T22:39:39.728968Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020ee.wal"} {"level":"warn","ts":"2026-04-28T22:39:43.832774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.25094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T22:39:43.832914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.802944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T22:39:43.832916Z","caller":"traceutil/trace.go:171","msg":"trace[365770578] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8112; }","duration":"197.434873ms","start":"2026-04-28T22:39:43.635443Z","end":"2026-04-28T22:39:43.832877Z","steps":["trace[365770578] 'range keys from in-memory index tree' (duration: 197.059445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:39:43.832889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.369668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6s2v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-28T22:39:43.832959Z","caller":"traceutil/trace.go:171","msg":"trace[963328663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8112; }","duration":"245.877786ms","start":"2026-04-28T22:39:43.587069Z","end":"2026-04-28T22:39:43.832947Z","steps":["trace[963328663] 'range keys from in-memory index tree' (duration: 245.723833ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:39:43.832975Z","caller":"traceutil/trace.go:171","msg":"trace[351643970] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6s2v5; range_end:; response_count:1; response_revision:8112; }","duration":"141.496071ms","start":"2026-04-28T22:39:43.691463Z","end":"2026-04-28T22:39:43.832959Z","steps":["trace[351643970] 'range keys from in-memory index tree' (duration: 141.182954ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:41:44.912206Z","caller":"traceutil/trace.go:171","msg":"trace[1326635461] transaction","detail":"{read_only:false; response_revision:9300; number_of_response:1; }","duration":"143.332347ms","start":"2026-04-28T22:41:44.768834Z","end":"2026-04-28T22:41:44.912166Z","steps":["trace[1326635461] 'process raft request' (duration: 96.395574ms)","trace[1326635461] 'compare' (duration: 46.770069ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:42:10.036127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6625} {"level":"info","ts":"2026-04-28T22:42:10.130409Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6625,"took":"91.786633ms","hash":1552741930,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":27041792,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-28T22:42:10.130487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1552741930,"revision":6625,"compact-revision":3223} {"level":"info","ts":"2026-04-28T22:42:16.686925Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T22:42:16.69156Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-28T22:42:16.691675Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-28T22:45:19.255014Z","caller":"traceutil/trace.go:171","msg":"trace[718568593] transaction","detail":"{read_only:false; response_revision:11450; number_of_response:1; }","duration":"105.728944ms","start":"2026-04-28T22:45:19.149245Z","end":"2026-04-28T22:45:19.254974Z","steps":["trace[718568593] 'process raft request' (duration: 105.50793ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:45:19.384026Z","caller":"traceutil/trace.go:171","msg":"trace[1075261748] linearizableReadLoop","detail":"{readStateIndex:11960; appliedIndex:11959; }","duration":"110.127332ms","start":"2026-04-28T22:45:19.273868Z","end":"2026-04-28T22:45:19.383995Z","steps":["trace[1075261748] 'read index received' (duration: 67.48627ms)","trace[1075261748] 'applied index is now lower than readState.Index' (duration: 42.639742ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:45:19.384069Z","caller":"traceutil/trace.go:171","msg":"trace[1060457495] transaction","detail":"{read_only:false; response_revision:11451; number_of_response:1; }","duration":"121.147712ms","start":"2026-04-28T22:45:19.262869Z","end":"2026-04-28T22:45:19.384017Z","steps":["trace[1060457495] 'process raft request' (duration: 78.457759ms)","trace[1060457495] 'compare' (duration: 42.5121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T22:45:19.384207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.307415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T22:45:19.384291Z","caller":"traceutil/trace.go:171","msg":"trace[5693421] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:11451; }","duration":"110.459039ms","start":"2026-04-28T22:45:19.27381Z","end":"2026-04-28T22:45:19.384269Z","steps":["trace[5693421] 'agreement among raft nodes before linearized reading' (duration: 110.285436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:47:10.04349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9517} {"level":"info","ts":"2026-04-28T22:47:10.133917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9517,"took":"85.854336ms","hash":3164280601,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":28950528,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T22:47:10.133987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3164280601,"revision":9517,"compact-revision":6625} {"level":"info","ts":"2026-04-28T22:51:27.702013Z","caller":"traceutil/trace.go:171","msg":"trace[71998469] linearizableReadLoop","detail":"{readStateIndex:15114; appliedIndex:15113; }","duration":"188.925192ms","start":"2026-04-28T22:51:27.513061Z","end":"2026-04-28T22:51:27.701986Z","steps":["trace[71998469] 'read index received' (duration: 188.785329ms)","trace[71998469] 'applied index is now lower than readState.Index' (duration: 138.723µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:51:27.702129Z","caller":"traceutil/trace.go:171","msg":"trace[1674966608] transaction","detail":"{read_only:false; response_revision:14447; number_of_response:1; }","duration":"210.254453ms","start":"2026-04-28T22:51:27.491862Z","end":"2026-04-28T22:51:27.702117Z","steps":["trace[1674966608] 'process raft request' (duration: 209.940887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:51:27.702333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.242118ms","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-04-28T22:51:27.702438Z","caller":"traceutil/trace.go:171","msg":"trace[691679026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:14447; }","duration":"189.389641ms","start":"2026-04-28T22:51:27.513013Z","end":"2026-04-28T22:51:27.702402Z","steps":["trace[691679026] 'agreement among raft nodes before linearized reading' (duration: 189.228058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:51:27.703028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.36033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T22:51:27.703152Z","caller":"traceutil/trace.go:171","msg":"trace[1372392247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14448; }","duration":"137.502013ms","start":"2026-04-28T22:51:27.565635Z","end":"2026-04-28T22:51:27.703137Z","steps":["trace[1372392247] 'agreement among raft nodes before linearized reading' (duration: 137.244068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:51:27.7032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.985255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T22:51:27.703265Z","caller":"traceutil/trace.go:171","msg":"trace[536381464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14448; }","duration":"123.084027ms","start":"2026-04-28T22:51:27.580164Z","end":"2026-04-28T22:51:27.703248Z","steps":["trace[536381464] 'agreement among raft nodes before linearized reading' (duration: 122.937025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:51:27.703264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.337692ms","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-04-28T22:51:27.703056Z","caller":"traceutil/trace.go:171","msg":"trace[1562673551] transaction","detail":"{read_only:false; response_revision:14448; number_of_response:1; }","duration":"170.486186ms","start":"2026-04-28T22:51:27.532548Z","end":"2026-04-28T22:51:27.703034Z","steps":["trace[1562673551] 'process raft request' (duration: 170.267962ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:51:27.703322Z","caller":"traceutil/trace.go:171","msg":"trace[1147161556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14448; }","duration":"123.415674ms","start":"2026-04-28T22:51:27.57989Z","end":"2026-04-28T22:51:27.703306Z","steps":["trace[1147161556] 'agreement among raft nodes before linearized reading' (duration: 123.221ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:51:43.231829Z","caller":"traceutil/trace.go:171","msg":"trace[2072732572] linearizableReadLoop","detail":"{readStateIndex:15239; appliedIndex:15238; }","duration":"124.22094ms","start":"2026-04-28T22:51:43.107582Z","end":"2026-04-28T22:51:43.231803Z","steps":["trace[2072732572] 'read index received' (duration: 124.031176ms)","trace[2072732572] 'applied index is now lower than readState.Index' (duration: 188.804µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:51:43.231967Z","caller":"traceutil/trace.go:171","msg":"trace[730877162] transaction","detail":"{read_only:false; response_revision:14565; number_of_response:1; }","duration":"142.42634ms","start":"2026-04-28T22:51:43.089482Z","end":"2026-04-28T22:51:43.231909Z","steps":["trace[730877162] 'process raft request' (duration: 142.145764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:51:43.232545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.904213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T22:51:43.232641Z","caller":"traceutil/trace.go:171","msg":"trace[393370618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14565; }","duration":"125.043977ms","start":"2026-04-28T22:51:43.107537Z","end":"2026-04-28T22:51:43.232581Z","steps":["trace[393370618] 'agreement among raft nodes before linearized reading' (duration: 124.915994ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:52:10.049863Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12382} {"level":"info","ts":"2026-04-28T22:52:10.139737Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12382,"took":"86.588805ms","hash":2628198978,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":26587136,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-28T22:52:10.139799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2628198978,"revision":12382,"compact-revision":9517} {"level":"info","ts":"2026-04-28T22:52:54.044073Z","caller":"traceutil/trace.go:171","msg":"trace[131496081] linearizableReadLoop","detail":"{readStateIndex:16298; appliedIndex:16297; }","duration":"127.682828ms","start":"2026-04-28T22:52:53.916357Z","end":"2026-04-28T22:52:54.04404Z","steps":["trace[131496081] 'read index received' (duration: 120.70203ms)","trace[131496081] 'applied index is now lower than readState.Index' (duration: 6.979728ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:52:54.044112Z","caller":"traceutil/trace.go:171","msg":"trace[433589106] transaction","detail":"{read_only:false; response_revision:15579; number_of_response:1; }","duration":"132.894291ms","start":"2026-04-28T22:52:53.911188Z","end":"2026-04-28T22:52:54.044082Z","steps":["trace[433589106] 'process raft request' (duration: 125.860382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:52:54.044593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.199518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6s2v5\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-28T22:52:54.044647Z","caller":"traceutil/trace.go:171","msg":"trace[778414070] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6s2v5; range_end:; response_count:1; response_revision:15579; }","duration":"128.291511ms","start":"2026-04-28T22:52:53.916342Z","end":"2026-04-28T22:52:54.044633Z","steps":["trace[778414070] 'agreement among raft nodes before linearized reading' (duration: 127.857451ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:57:10.057568Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14962} {"level":"info","ts":"2026-04-28T22:57:10.150844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14962,"took":"90.263196ms","hash":3696005150,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":32591872,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-28T22:57:10.150964Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3696005150,"revision":14962,"compact-revision":12382} {"level":"warn","ts":"2026-04-28T22:58:29.585369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.98337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-ckl8m\" ","response":"range_response_count:1 size:9788"} {"level":"info","ts":"2026-04-28T22:58:29.585478Z","caller":"traceutil/trace.go:171","msg":"trace[1722095478] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-ckl8m; range_end:; response_count:1; response_revision:18564; }","duration":"199.135572ms","start":"2026-04-28T22:58:29.386324Z","end":"2026-04-28T22:58:29.58546Z","steps":["trace[1722095478] 'range keys from in-memory index tree' (duration: 198.826566ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:58:29.740254Z","caller":"traceutil/trace.go:171","msg":"trace[2117120214] transaction","detail":"{read_only:false; response_revision:18565; number_of_response:1; }","duration":"142.628608ms","start":"2026-04-28T22:58:29.597593Z","end":"2026-04-28T22:58:29.740221Z","steps":["trace[2117120214] 'process raft request' (duration: 142.404104ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:58:29.81079Z","caller":"traceutil/trace.go:171","msg":"trace[1549446792] linearizableReadLoop","detail":"{readStateIndex:19436; appliedIndex:19433; }","duration":"144.837363ms","start":"2026-04-28T22:58:29.665924Z","end":"2026-04-28T22:58:29.810762Z","steps":["trace[1549446792] 'read index received' (duration: 74.086444ms)","trace[1549446792] 'applied index is now lower than readState.Index' (duration: 70.749619ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:58:29.8109Z","caller":"traceutil/trace.go:171","msg":"trace[1233539823] transaction","detail":"{read_only:false; response_revision:18567; number_of_response:1; }","duration":"204.744534ms","start":"2026-04-28T22:58:29.606097Z","end":"2026-04-28T22:58:29.810841Z","steps":["trace[1233539823] 'process raft request' (duration: 204.570731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T22:58:29.811051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.114478ms","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-04-28T22:58:29.811098Z","caller":"traceutil/trace.go:171","msg":"trace[701555962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18567; }","duration":"145.22843ms","start":"2026-04-28T22:58:29.665856Z","end":"2026-04-28T22:58:29.811085Z","steps":["trace[701555962] 'agreement among raft nodes before linearized reading' (duration: 145.066197ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:58:29.811104Z","caller":"traceutil/trace.go:171","msg":"trace[1464051227] transaction","detail":"{read_only:false; response_revision:18566; number_of_response:1; }","duration":"206.098322ms","start":"2026-04-28T22:58:29.604984Z","end":"2026-04-28T22:58:29.811082Z","steps":["trace[1464051227] 'process raft request' (duration: 182.562713ms)","trace[1464051227] 'compare' (duration: 22.925536ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T22:58:36.028736Z","caller":"traceutil/trace.go:171","msg":"trace[771606467] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18679; }","duration":"105.431258ms","start":"2026-04-28T22:58:35.923281Z","end":"2026-04-28T22:58:36.028712Z","steps":["trace[771606467] 'process raft request' (duration: 102.825616ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T22:59:08.615922Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T22:59:08.622972Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-28T22:59:08.62308Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-28T23:00:10.695218Z","caller":"traceutil/trace.go:171","msg":"trace[1088324466] transaction","detail":"{read_only:false; response_revision:19770; number_of_response:1; }","duration":"110.063331ms","start":"2026-04-28T23:00:10.585127Z","end":"2026-04-28T23:00:10.69519Z","steps":["trace[1088324466] 'process raft request' (duration: 48.049617ms)","trace[1088324466] 'compare' (duration: 61.82805ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:00:10.702068Z","caller":"traceutil/trace.go:171","msg":"trace[1976237933] transaction","detail":"{read_only:false; response_revision:19771; number_of_response:1; }","duration":"116.682242ms","start":"2026-04-28T23:00:10.585357Z","end":"2026-04-28T23:00:10.702039Z","steps":["trace[1976237933] 'process raft request' (duration: 116.478158ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:00:10.702192Z","caller":"traceutil/trace.go:171","msg":"trace[721861280] transaction","detail":"{read_only:false; response_revision:19772; number_of_response:1; }","duration":"115.781865ms","start":"2026-04-28T23:00:10.586392Z","end":"2026-04-28T23:00:10.702174Z","steps":["trace[721861280] 'process raft request' (duration: 115.588801ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:02:10.064364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17903} {"level":"info","ts":"2026-04-28T23:02:10.175869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17903,"took":"108.309007ms","hash":259210049,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":33185792,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-28T23:02:10.175956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":259210049,"revision":17903,"compact-revision":14962} {"level":"warn","ts":"2026-04-28T23:05:55.070429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.024337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:05:55.07077Z","caller":"traceutil/trace.go:171","msg":"trace[1222389387] transaction","detail":"{read_only:false; response_revision:23005; number_of_response:1; }","duration":"367.969423ms","start":"2026-04-28T23:05:54.702768Z","end":"2026-04-28T23:05:55.070737Z","steps":["trace[1222389387] 'process raft request' (duration: 179.544688ms)","trace[1222389387] 'compare' (duration: 187.658669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:05:55.070885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:05:54.702753Z","time spent":"368.071535ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:05:55.070948Z","caller":"traceutil/trace.go:171","msg":"trace[1953991736] linearizableReadLoop","detail":"{readStateIndex:24075; appliedIndex:24074; }","duration":"313.272942ms","start":"2026-04-28T23:05:54.757629Z","end":"2026-04-28T23:05:55.070902Z","steps":["trace[1953991736] 'read index received' (duration: 124.654023ms)","trace[1953991736] 'applied index is now lower than readState.Index' (duration: 188.613609ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:05:55.071198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.534847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-04-28T23:05:55.071296Z","caller":"traceutil/trace.go:171","msg":"trace[1255920692] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23005; }","duration":"313.673731ms","start":"2026-04-28T23:05:54.757597Z","end":"2026-04-28T23:05:55.07127Z","steps":["trace[1255920692] 'agreement among raft nodes before linearized reading' (duration: 313.361974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:05:55.071345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.87183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T23:05:55.07139Z","caller":"traceutil/trace.go:171","msg":"trace[455180855] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:23006; }","duration":"105.973172ms","start":"2026-04-28T23:05:54.965406Z","end":"2026-04-28T23:05:55.071379Z","steps":["trace[455180855] 'agreement among raft nodes before linearized reading' (duration: 105.86994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:05:55.07135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:05:54.757571Z","time spent":"313.770192ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-28T23:05:55.071228Z","caller":"traceutil/trace.go:171","msg":"trace[1999793849] transaction","detail":"{read_only:false; response_revision:23006; number_of_response:1; }","duration":"177.09405ms","start":"2026-04-28T23:05:54.894122Z","end":"2026-04-28T23:05:55.071216Z","steps":["trace[1999793849] 'process raft request' (duration: 176.426165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:05:55.072042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.253831ms","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-04-28T23:05:55.072106Z","caller":"traceutil/trace.go:171","msg":"trace[1216962376] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23006; }","duration":"312.337314ms","start":"2026-04-28T23:05:54.759737Z","end":"2026-04-28T23:05:55.072074Z","steps":["trace[1216962376] 'agreement among raft nodes before linearized reading' (duration: 311.452587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:05:55.072135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:05:54.759715Z","time spent":"312.413636ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T23:05:55.230505Z","caller":"traceutil/trace.go:171","msg":"trace[1246191236] transaction","detail":"{read_only:false; response_revision:23008; number_of_response:1; }","duration":"149.569631ms","start":"2026-04-28T23:05:55.080894Z","end":"2026-04-28T23:05:55.230463Z","steps":["trace[1246191236] 'process raft request' (duration: 130.420189ms)","trace[1246191236] 'compare' (duration: 18.873705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:05:55.23064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.162934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:05:55.230723Z","caller":"traceutil/trace.go:171","msg":"trace[418855257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23008; }","duration":"122.283637ms","start":"2026-04-28T23:05:55.108417Z","end":"2026-04-28T23:05:55.230701Z","steps":["trace[418855257] 'agreement among raft nodes before linearized reading' (duration: 122.130714ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:05:55.23067Z","caller":"traceutil/trace.go:171","msg":"trace[39847150] linearizableReadLoop","detail":"{readStateIndex:24078; appliedIndex:24077; }","duration":"121.915599ms","start":"2026-04-28T23:05:55.10845Z","end":"2026-04-28T23:05:55.230366Z","steps":["trace[39847150] 'read index received' (duration: 183.023µs)","trace[39847150] 'applied index is now lower than readState.Index' (duration: 121.731566ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:07:10.071582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21048} {"level":"info","ts":"2026-04-28T23:07:10.199516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21048,"took":"124.089322ms","hash":4092102465,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":33902592,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-28T23:07:10.199577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4092102465,"revision":21048,"compact-revision":17903} {"level":"info","ts":"2026-04-28T23:09:20.118813Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000681d.wal"} {"level":"info","ts":"2026-04-28T23:12:10.076716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23810} {"level":"info","ts":"2026-04-28T23:12:10.20268Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23810,"took":"121.400719ms","hash":3322918947,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":42897408,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-28T23:12:10.202738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3322918947,"revision":23810,"compact-revision":21048} {"level":"warn","ts":"2026-04-28T23:12:29.603838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.324949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"warn","ts":"2026-04-28T23:12:29.603929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.571316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-04-28T23:12:29.603982Z","caller":"traceutil/trace.go:171","msg":"trace[855706329] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:27765; }","duration":"147.459621ms","start":"2026-04-28T23:12:29.456473Z","end":"2026-04-28T23:12:29.603933Z","steps":["trace[855706329] 'range keys from in-memory index tree' (duration: 147.163894ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:29.604013Z","caller":"traceutil/trace.go:171","msg":"trace[787673164] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27765; }","duration":"331.708008ms","start":"2026-04-28T23:12:29.27229Z","end":"2026-04-28T23:12:29.603998Z","steps":["trace[787673164] 'range keys from in-memory index tree' (duration: 331.426412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:29.604046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:29.272264Z","time spent":"331.771489ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":670,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T23:12:29.604217Z","caller":"traceutil/trace.go:171","msg":"trace[444300920] transaction","detail":"{read_only:false; response_revision:27766; number_of_response:1; }","duration":"142.859359ms","start":"2026-04-28T23:12:29.461315Z","end":"2026-04-28T23:12:29.604174Z","steps":["trace[444300920] 'process raft request' (duration: 106.298887ms)","trace[444300920] 'compare' (duration: 36.213105ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:29.900197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.205565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:12:29.900386Z","caller":"traceutil/trace.go:171","msg":"trace[1125834867] transaction","detail":"{read_only:false; response_revision:27767; number_of_response:1; }","duration":"279.3613ms","start":"2026-04-28T23:12:29.621004Z","end":"2026-04-28T23:12:29.900365Z","steps":["trace[1125834867] 'process raft request' (duration: 140.883589ms)","trace[1125834867] 'compare' (duration: 137.796878ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:30.18739Z","caller":"traceutil/trace.go:171","msg":"trace[152087821] linearizableReadLoop","detail":"{readStateIndex:29036; appliedIndex:29034; }","duration":"508.105368ms","start":"2026-04-28T23:12:29.679261Z","end":"2026-04-28T23:12:30.187367Z","steps":["trace[152087821] 'read index received' (duration: 82.638564ms)","trace[152087821] 'applied index is now lower than readState.Index' (duration: 425.465884ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:30.187662Z","caller":"traceutil/trace.go:171","msg":"trace[1986725508] transaction","detail":"{read_only:false; response_revision:27768; number_of_response:1; }","duration":"564.909065ms","start":"2026-04-28T23:12:29.62273Z","end":"2026-04-28T23:12:30.187639Z","steps":["trace[1986725508] 'process raft request' (duration: 544.934215ms)","trace[1986725508] 'compare' (duration: 19.48407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:30.187777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:29.622713Z","time spent":"565.006306ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:30.187843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.579807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-04-28T23:12:30.1879Z","caller":"traceutil/trace.go:171","msg":"trace[1590248246] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:27768; }","duration":"508.665078ms","start":"2026-04-28T23:12:29.679219Z","end":"2026-04-28T23:12:30.187884Z","steps":["trace[1590248246] 'agreement among raft nodes before linearized reading' (duration: 508.488795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.18794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:29.679205Z","time spent":"508.72808ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"info","ts":"2026-04-28T23:12:30.473234Z","caller":"traceutil/trace.go:171","msg":"trace[2083166398] transaction","detail":"{read_only:false; response_revision:27771; number_of_response:1; }","duration":"276.509694ms","start":"2026-04-28T23:12:30.196702Z","end":"2026-04-28T23:12:30.473212Z","steps":["trace[2083166398] 'process raft request' (duration: 276.431842ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:30.473313Z","caller":"traceutil/trace.go:171","msg":"trace[616403813] transaction","detail":"{read_only:false; response_revision:27769; number_of_response:1; }","duration":"563.270511ms","start":"2026-04-28T23:12:29.910029Z","end":"2026-04-28T23:12:30.4733Z","steps":["trace[616403813] 'process raft request' (duration: 520.105466ms)","trace[616403813] 'compare' (duration: 42.796677ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:30.473239Z","caller":"traceutil/trace.go:171","msg":"trace[313939628] transaction","detail":"{read_only:false; response_revision:27770; number_of_response:1; }","duration":"511.971105ms","start":"2026-04-28T23:12:29.961232Z","end":"2026-04-28T23:12:30.473203Z","steps":["trace[313939628] 'process raft request' (duration: 511.831472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.473637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.427232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T23:12:30.47363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.941902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T23:12:30.473678Z","caller":"traceutil/trace.go:171","msg":"trace[1083848733] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:27772; }","duration":"438.491975ms","start":"2026-04-28T23:12:30.035177Z","end":"2026-04-28T23:12:30.473669Z","steps":["trace[1083848733] 'agreement among raft nodes before linearized reading' (duration: 438.430074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.47371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.035161Z","time spent":"438.541365ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-28T23:12:30.473694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.674977ms","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-04-28T23:12:30.473717Z","caller":"traceutil/trace.go:171","msg":"trace[1781602803] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:27772; }","duration":"383.062975ms","start":"2026-04-28T23:12:30.090633Z","end":"2026-04-28T23:12:30.473696Z","steps":["trace[1781602803] 'agreement among raft nodes before linearized reading' (duration: 382.924243ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:30.473421Z","caller":"traceutil/trace.go:171","msg":"trace[1901969904] linearizableReadLoop","detail":"{readStateIndex:29038; appliedIndex:29036; }","duration":"285.950092ms","start":"2026-04-28T23:12:30.187456Z","end":"2026-04-28T23:12:30.473406Z","steps":["trace[1901969904] 'read index received' (duration: 242.691565ms)","trace[1901969904] 'applied index is now lower than readState.Index' (duration: 43.257227ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:30.47376Z","caller":"traceutil/trace.go:171","msg":"trace[2083776908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27772; }","duration":"352.745748ms","start":"2026-04-28T23:12:30.120998Z","end":"2026-04-28T23:12:30.473744Z","steps":["trace[2083776908] 'agreement among raft nodes before linearized reading' (duration: 352.555435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.473763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.090619Z","time spent":"383.134166ms","remote":"127.0.0.1:36324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-28T23:12:30.473599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:29.910012Z","time spent":"563.463355ms","remote":"127.0.0.1:36386","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-04-28T23:12:30.473791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.120942Z","time spent":"352.8437ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T23:12:30.473584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.400304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-28T23:12:30.473887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.455471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T23:12:30.473886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.647666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-28T23:12:30.473909Z","caller":"traceutil/trace.go:171","msg":"trace[2060533251] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:27772; }","duration":"221.529942ms","start":"2026-04-28T23:12:30.252372Z","end":"2026-04-28T23:12:30.473902Z","steps":["trace[2060533251] 'agreement among raft nodes before linearized reading' (duration: 221.489032ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:30.473919Z","caller":"traceutil/trace.go:171","msg":"trace[43615387] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:27772; }","duration":"282.712727ms","start":"2026-04-28T23:12:30.191195Z","end":"2026-04-28T23:12:30.473908Z","steps":["trace[43615387] 'agreement among raft nodes before linearized reading' (duration: 282.608644ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:30.473905Z","caller":"traceutil/trace.go:171","msg":"trace[499899224] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27772; }","duration":"569.7323ms","start":"2026-04-28T23:12:29.904138Z","end":"2026-04-28T23:12:30.47387Z","steps":["trace[499899224] 'agreement among raft nodes before linearized reading' (duration: 569.311091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.47395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.598003ms","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-04-28T23:12:30.473985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:29.904127Z","time spent":"569.831243ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T23:12:30.47401Z","caller":"traceutil/trace.go:171","msg":"trace[2125614102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27772; }","duration":"152.673624ms","start":"2026-04-28T23:12:30.321318Z","end":"2026-04-28T23:12:30.473991Z","steps":["trace[2125614102] 'agreement among raft nodes before linearized reading' (duration: 152.531341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.473695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:29.961207Z","time spent":"512.410714ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:30.474055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.698036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-28T23:12:30.474137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.313179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e65c8b8c-0f39-483b-bc97-14b0e5b25ab0\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-04-28T23:12:30.474143Z","caller":"traceutil/trace.go:171","msg":"trace[1346264339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27772; }","duration":"249.812088ms","start":"2026-04-28T23:12:30.224318Z","end":"2026-04-28T23:12:30.47413Z","steps":["trace[1346264339] 'agreement among raft nodes before linearized reading' (duration: 249.679655ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:30.474174Z","caller":"traceutil/trace.go:171","msg":"trace[1250297761] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e65c8b8c-0f39-483b-bc97-14b0e5b25ab0; range_end:; response_count:1; response_revision:27772; }","duration":"282.365371ms","start":"2026-04-28T23:12:30.1918Z","end":"2026-04-28T23:12:30.474165Z","steps":["trace[1250297761] 'agreement among raft nodes before linearized reading' (duration: 282.274229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.474203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.367088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-04-28T23:12:30.474067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.264197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-28T23:12:30.474229Z","caller":"traceutil/trace.go:171","msg":"trace[1408868790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27772; }","duration":"147.411609ms","start":"2026-04-28T23:12:30.326812Z","end":"2026-04-28T23:12:30.474224Z","steps":["trace[1408868790] 'agreement among raft nodes before linearized reading' (duration: 147.356078ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:30.47425Z","caller":"traceutil/trace.go:171","msg":"trace[1304476986] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27772; }","duration":"154.481141ms","start":"2026-04-28T23:12:30.319761Z","end":"2026-04-28T23:12:30.474242Z","steps":["trace[1304476986] 'agreement among raft nodes before linearized reading' (duration: 154.261497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:30.775218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.386079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:12:30.775323Z","caller":"traceutil/trace.go:171","msg":"trace[2092781628] transaction","detail":"{read_only:false; response_revision:27773; number_of_response:1; }","duration":"296.264569ms","start":"2026-04-28T23:12:30.479039Z","end":"2026-04-28T23:12:30.775304Z","steps":["trace[2092781628] 'process raft request' (duration: 130.707136ms)","trace[2092781628] 'compare' (duration: 165.158855ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:31.050983Z","caller":"traceutil/trace.go:171","msg":"trace[1044400030] transaction","detail":"{read_only:false; response_revision:27783; number_of_response:1; }","duration":"210.270267ms","start":"2026-04-28T23:12:30.840685Z","end":"2026-04-28T23:12:31.050956Z","steps":["trace[1044400030] 'process raft request' (duration: 210.222606ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:31.051339Z","caller":"traceutil/trace.go:171","msg":"trace[1682039280] transaction","detail":"{read_only:false; response_revision:27779; number_of_response:1; }","duration":"571.638788ms","start":"2026-04-28T23:12:30.47967Z","end":"2026-04-28T23:12:31.051309Z","steps":["trace[1682039280] 'process raft request' (duration: 571.089687ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:31.051368Z","caller":"traceutil/trace.go:171","msg":"trace[557866844] transaction","detail":"{read_only:false; response_revision:27776; number_of_response:1; }","duration":"572.122738ms","start":"2026-04-28T23:12:30.479215Z","end":"2026-04-28T23:12:31.051337Z","steps":["trace[557866844] 'process raft request' (duration: 571.440514ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:31.051393Z","caller":"traceutil/trace.go:171","msg":"trace[1395540144] transaction","detail":"{read_only:false; response_revision:27774; number_of_response:1; }","duration":"572.183449ms","start":"2026-04-28T23:12:30.479188Z","end":"2026-04-28T23:12:31.051371Z","steps":["trace[1395540144] 'process raft request' (duration: 490.108007ms)","trace[1395540144] 'compare' (duration: 81.010921ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:31.051411Z","caller":"traceutil/trace.go:171","msg":"trace[1392167636] linearizableReadLoop","detail":"{readStateIndex:29049; appliedIndex:29041; }","duration":"394.326341ms","start":"2026-04-28T23:12:30.657063Z","end":"2026-04-28T23:12:31.05139Z","steps":["trace[1392167636] 'read index received' (duration: 312.245359ms)","trace[1392167636] 'applied index is now lower than readState.Index' (duration: 82.079092ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:31.051477Z","caller":"traceutil/trace.go:171","msg":"trace[950388588] transaction","detail":"{read_only:false; response_revision:27780; number_of_response:1; }","duration":"571.219ms","start":"2026-04-28T23:12:30.480235Z","end":"2026-04-28T23:12:31.051454Z","steps":["trace[950388588] 'process raft request' (duration: 570.561067ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:31.051511Z","caller":"traceutil/trace.go:171","msg":"trace[595700703] transaction","detail":"{read_only:false; response_revision:27781; number_of_response:1; }","duration":"534.551536ms","start":"2026-04-28T23:12:30.516951Z","end":"2026-04-28T23:12:31.051502Z","steps":["trace[595700703] 'process raft request' (duration: 533.880083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:31.051502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.479186Z","time spent":"572.24159ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:31.051502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.47916Z","time spent":"572.27351ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:31.051424Z","caller":"traceutil/trace.go:171","msg":"trace[749051568] transaction","detail":"{read_only:false; response_revision:27782; number_of_response:1; }","duration":"345.319669ms","start":"2026-04-28T23:12:30.706088Z","end":"2026-04-28T23:12:31.051408Z","steps":["trace[749051568] 'process raft request' (duration: 344.787939ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:31.051547Z","caller":"traceutil/trace.go:171","msg":"trace[371498382] transaction","detail":"{read_only:false; response_revision:27778; number_of_response:1; }","duration":"572.121167ms","start":"2026-04-28T23:12:30.479415Z","end":"2026-04-28T23:12:31.051536Z","steps":["trace[371498382] 'process raft request' (duration: 571.313332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:31.051558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.480224Z","time spent":"571.295581ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:31.0516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.516938Z","time spent":"534.594357ms","remote":"127.0.0.1:36386","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-04-28T23:12:31.051571Z","caller":"traceutil/trace.go:171","msg":"trace[600431826] transaction","detail":"{read_only:false; response_revision:27777; number_of_response:1; }","duration":"572.228089ms","start":"2026-04-28T23:12:30.479308Z","end":"2026-04-28T23:12:31.051536Z","steps":["trace[600431826] 'process raft request' (duration: 571.390703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:31.05161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.479406Z","time spent":"572.176978ms","remote":"127.0.0.1:36386","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-04-28T23:12:31.051612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.706063Z","time spent":"345.479103ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:31.051499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.479659Z","time spent":"571.75454ms","remote":"127.0.0.1:36386","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-04-28T23:12:31.051642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.582795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:31.051679Z","caller":"traceutil/trace.go:171","msg":"trace[1691376997] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:27783; }","duration":"394.666257ms","start":"2026-04-28T23:12:30.657001Z","end":"2026-04-28T23:12:31.051668Z","steps":["trace[1691376997] 'agreement among raft nodes before linearized reading' (duration: 394.600686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:31.051724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.656977Z","time spent":"394.734769ms","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-28T23:12:31.051735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.47929Z","time spent":"572.361563ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:31.051757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.528021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:31.051782Z","caller":"traceutil/trace.go:171","msg":"trace[82630332] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:27783; }","duration":"177.602723ms","start":"2026-04-28T23:12:30.874172Z","end":"2026-04-28T23:12:31.051775Z","steps":["trace[82630332] 'agreement among raft nodes before linearized reading' (duration: 177.533322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:31.051828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.912701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-04-28T23:12:31.051937Z","caller":"traceutil/trace.go:171","msg":"trace[778871304] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:27783; }","duration":"366.030833ms","start":"2026-04-28T23:12:30.685862Z","end":"2026-04-28T23:12:31.051893Z","steps":["trace[778871304] 'agreement among raft nodes before linearized reading' (duration: 365.769058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:31.052002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.685851Z","time spent":"366.141395ms","remote":"127.0.0.1:36306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1959,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-28T23:12:31.052093Z","caller":"traceutil/trace.go:171","msg":"trace[1494274577] transaction","detail":"{read_only:false; response_revision:27775; number_of_response:1; }","duration":"572.877862ms","start":"2026-04-28T23:12:30.479203Z","end":"2026-04-28T23:12:31.052081Z","steps":["trace[1494274577] 'process raft request' (duration: 571.356732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:31.052147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:30.479179Z","time spent":"572.941155ms","remote":"127.0.0.1:36386","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-04-28T23:12:36.702687Z","caller":"traceutil/trace.go:171","msg":"trace[749787714] transaction","detail":"{read_only:false; response_revision:27819; number_of_response:1; }","duration":"205.174526ms","start":"2026-04-28T23:12:36.497475Z","end":"2026-04-28T23:12:36.70265Z","steps":["trace[749787714] 'process raft request' (duration: 204.804088ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:36.733129Z","caller":"traceutil/trace.go:171","msg":"trace[690124590] linearizableReadLoop","detail":"{readStateIndex:29091; appliedIndex:29088; }","duration":"215.234936ms","start":"2026-04-28T23:12:36.51787Z","end":"2026-04-28T23:12:36.733105Z","steps":["trace[690124590] 'read index received' (duration: 184.562223ms)","trace[690124590] 'applied index is now lower than readState.Index' (duration: 30.671533ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:36.733206Z","caller":"traceutil/trace.go:171","msg":"trace[430126148] transaction","detail":"{read_only:false; response_revision:27821; number_of_response:1; }","duration":"233.616594ms","start":"2026-04-28T23:12:36.499583Z","end":"2026-04-28T23:12:36.7332Z","steps":["trace[430126148] 'process raft request' (duration: 233.469611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:36.733332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.430451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:36.733388Z","caller":"traceutil/trace.go:171","msg":"trace[439415809] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:27821; }","duration":"215.538383ms","start":"2026-04-28T23:12:36.517834Z","end":"2026-04-28T23:12:36.733373Z","steps":["trace[439415809] 'agreement among raft nodes before linearized reading' (duration: 215.42358ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:36.733144Z","caller":"traceutil/trace.go:171","msg":"trace[1895474336] transaction","detail":"{read_only:false; response_revision:27820; number_of_response:1; }","duration":"234.667115ms","start":"2026-04-28T23:12:36.498443Z","end":"2026-04-28T23:12:36.73311Z","steps":["trace[1895474336] 'process raft request' (duration: 224.340518ms)","trace[1895474336] 'compare' (duration: 10.131453ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:36.733412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.536683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:36.733511Z","caller":"traceutil/trace.go:171","msg":"trace[1240813444] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:27821; }","duration":"197.683126ms","start":"2026-04-28T23:12:36.5358Z","end":"2026-04-28T23:12:36.733483Z","steps":["trace[1240813444] 'agreement among raft nodes before linearized reading' (duration: 197.529703ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:36.919739Z","caller":"traceutil/trace.go:171","msg":"trace[2034235580] transaction","detail":"{read_only:false; response_revision:27822; number_of_response:1; }","duration":"119.766287ms","start":"2026-04-28T23:12:36.799944Z","end":"2026-04-28T23:12:36.91971Z","steps":["trace[2034235580] 'process raft request' (duration: 108.986351ms)","trace[2034235580] 'compare' (duration: 10.586742ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:37.386841Z","caller":"traceutil/trace.go:171","msg":"trace[444241144] linearizableReadLoop","detail":"{readStateIndex:29094; appliedIndex:29093; }","duration":"296.990363ms","start":"2026-04-28T23:12:37.089819Z","end":"2026-04-28T23:12:37.38681Z","steps":["trace[444241144] 'read index received' (duration: 235.457761ms)","trace[444241144] 'applied index is now lower than readState.Index' (duration: 61.531072ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:37.386883Z","caller":"traceutil/trace.go:171","msg":"trace[1913073739] transaction","detail":"{read_only:false; response_revision:27824; number_of_response:1; }","duration":"298.189428ms","start":"2026-04-28T23:12:37.088657Z","end":"2026-04-28T23:12:37.386847Z","steps":["trace[1913073739] 'process raft request' (duration: 236.779639ms)","trace[1913073739] 'compare' (duration: 61.231815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:37.387022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.188997ms","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-04-28T23:12:37.38707Z","caller":"traceutil/trace.go:171","msg":"trace[868921575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27824; }","duration":"297.283869ms","start":"2026-04-28T23:12:37.089774Z","end":"2026-04-28T23:12:37.387058Z","steps":["trace[868921575] 'agreement among raft nodes before linearized reading' (duration: 297.129066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.387155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.091595ms","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":"warn","ts":"2026-04-28T23:12:37.387416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.551993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:37.387478Z","caller":"traceutil/trace.go:171","msg":"trace[1034133851] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:27824; }","duration":"182.682486ms","start":"2026-04-28T23:12:37.204779Z","end":"2026-04-28T23:12:37.387462Z","steps":["trace[1034133851] 'agreement among raft nodes before linearized reading' (duration: 182.535673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.387486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.191497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"warn","ts":"2026-04-28T23:12:37.387515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.085635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-04-28T23:12:37.387532Z","caller":"traceutil/trace.go:171","msg":"trace[1723690190] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27824; }","duration":"297.252338ms","start":"2026-04-28T23:12:37.090267Z","end":"2026-04-28T23:12:37.387519Z","steps":["trace[1723690190] 'agreement among raft nodes before linearized reading' (duration: 297.121635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.387546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.588394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T23:12:37.387591Z","caller":"traceutil/trace.go:171","msg":"trace[1721163866] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:27824; }","duration":"277.208827ms","start":"2026-04-28T23:12:37.110357Z","end":"2026-04-28T23:12:37.387566Z","steps":["trace[1721163866] 'agreement among raft nodes before linearized reading' (duration: 276.968332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:37.387626Z","caller":"traceutil/trace.go:171","msg":"trace[753758175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27824; }","duration":"295.685896ms","start":"2026-04-28T23:12:37.091917Z","end":"2026-04-28T23:12:37.387602Z","steps":["trace[753758175] 'agreement among raft nodes before linearized reading' (duration: 295.463692ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:37.387235Z","caller":"traceutil/trace.go:171","msg":"trace[215143562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27824; }","duration":"297.223618ms","start":"2026-04-28T23:12:37.089994Z","end":"2026-04-28T23:12:37.387217Z","steps":["trace[215143562] 'agreement among raft nodes before linearized reading' (duration: 296.997343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.387822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.133595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-04-28T23:12:37.38785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.050083ms","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-04-28T23:12:37.387896Z","caller":"traceutil/trace.go:171","msg":"trace[290299394] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27824; }","duration":"279.231617ms","start":"2026-04-28T23:12:37.108643Z","end":"2026-04-28T23:12:37.387875Z","steps":["trace[290299394] 'agreement among raft nodes before linearized reading' (duration: 278.818359ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:37.387907Z","caller":"traceutil/trace.go:171","msg":"trace[1796946422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27824; }","duration":"297.171316ms","start":"2026-04-28T23:12:37.090719Z","end":"2026-04-28T23:12:37.38789Z","steps":["trace[1796946422] 'agreement among raft nodes before linearized reading' (duration: 296.655416ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:37.718074Z","caller":"traceutil/trace.go:171","msg":"trace[1629368945] linearizableReadLoop","detail":"{readStateIndex:29099; appliedIndex:29094; }","duration":"324.344911ms","start":"2026-04-28T23:12:37.393705Z","end":"2026-04-28T23:12:37.71805Z","steps":["trace[1629368945] 'read index received' (duration: 11.619973ms)","trace[1629368945] 'applied index is now lower than readState.Index' (duration: 312.723998ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:37.718422Z","caller":"traceutil/trace.go:171","msg":"trace[371799840] transaction","detail":"{read_only:false; response_revision:27826; number_of_response:1; }","duration":"326.863231ms","start":"2026-04-28T23:12:37.391547Z","end":"2026-04-28T23:12:37.71841Z","steps":["trace[371799840] 'process raft request' (duration: 326.114286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.718521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.391526Z","time spent":"326.927732ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:37.718703Z","caller":"traceutil/trace.go:171","msg":"trace[1943333771] transaction","detail":"{read_only:false; response_revision:27827; number_of_response:1; }","duration":"326.85109ms","start":"2026-04-28T23:12:37.391841Z","end":"2026-04-28T23:12:37.718692Z","steps":["trace[1943333771] 'process raft request' (duration: 325.979833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.71877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.391815Z","time spent":"326.925331ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:37.718911Z","caller":"traceutil/trace.go:171","msg":"trace[1920185787] transaction","detail":"{read_only:false; response_revision:27828; number_of_response:1; }","duration":"326.724867ms","start":"2026-04-28T23:12:37.392177Z","end":"2026-04-28T23:12:37.718902Z","steps":["trace[1920185787] 'process raft request' (duration: 325.688667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.718966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.392167Z","time spent":"326.773158ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:37.719059Z","caller":"traceutil/trace.go:171","msg":"trace[505976609] transaction","detail":"{read_only:false; response_revision:27829; number_of_response:1; }","duration":"326.734757ms","start":"2026-04-28T23:12:37.392311Z","end":"2026-04-28T23:12:37.719046Z","steps":["trace[505976609] 'process raft request' (duration: 325.590345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.719109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.392287Z","time spent":"326.796479ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:37.719292Z","caller":"traceutil/trace.go:171","msg":"trace[391702508] transaction","detail":"{read_only:false; response_revision:27830; number_of_response:1; }","duration":"324.149076ms","start":"2026-04-28T23:12:37.395133Z","end":"2026-04-28T23:12:37.719282Z","steps":["trace[391702508] 'process raft request' (duration: 322.801019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.719351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.395107Z","time spent":"324.215856ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:37.719581Z","caller":"traceutil/trace.go:171","msg":"trace[1191500432] transaction","detail":"{read_only:false; response_revision:27831; number_of_response:1; }","duration":"319.546834ms","start":"2026-04-28T23:12:37.400005Z","end":"2026-04-28T23:12:37.719552Z","steps":["trace[1191500432] 'process raft request' (duration: 318.008843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.719745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.399994Z","time spent":"319.663146ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:37.719919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.207368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11495"} {"level":"info","ts":"2026-04-28T23:12:37.719986Z","caller":"traceutil/trace.go:171","msg":"trace[2110666589] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27831; }","duration":"326.286379ms","start":"2026-04-28T23:12:37.39369Z","end":"2026-04-28T23:12:37.719976Z","steps":["trace[2110666589] 'agreement among raft nodes before linearized reading' (duration: 326.115516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.72002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.393683Z","time spent":"326.32653ms","remote":"127.0.0.1:36330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11518,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-28T23:12:37.720191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.446582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:37.720228Z","caller":"traceutil/trace.go:171","msg":"trace[423705043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:27831; }","duration":"282.510883ms","start":"2026-04-28T23:12:37.437709Z","end":"2026-04-28T23:12:37.72022Z","steps":["trace[423705043] 'agreement among raft nodes before linearized reading' (duration: 282.452182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.720498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.87902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:37.72055Z","caller":"traceutil/trace.go:171","msg":"trace[1192118711] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:27831; }","duration":"200.988012ms","start":"2026-04-28T23:12:37.519549Z","end":"2026-04-28T23:12:37.720537Z","steps":["trace[1192118711] 'agreement among raft nodes before linearized reading' (duration: 200.8956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:37.996901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.376049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:12:37.996998Z","caller":"traceutil/trace.go:171","msg":"trace[1743123031] linearizableReadLoop","detail":"{readStateIndex:29102; appliedIndex:29101; }","duration":"268.240997ms","start":"2026-04-28T23:12:37.728741Z","end":"2026-04-28T23:12:37.996982Z","steps":["trace[1743123031] 'read index received' (duration: 116.712195ms)","trace[1743123031] 'applied index is now lower than readState.Index' (duration: 151.527542ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:37.997133Z","caller":"traceutil/trace.go:171","msg":"trace[1392157256] transaction","detail":"{read_only:false; response_revision:27832; number_of_response:1; }","duration":"270.459222ms","start":"2026-04-28T23:12:37.726553Z","end":"2026-04-28T23:12:37.997012Z","steps":["trace[1392157256] 'process raft request' (duration: 118.886479ms)","trace[1392157256] 'compare' (duration: 151.156544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:37.997218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.456092ms","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-04-28T23:12:37.997315Z","caller":"traceutil/trace.go:171","msg":"trace[1373348118] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:27832; }","duration":"268.589814ms","start":"2026-04-28T23:12:37.728703Z","end":"2026-04-28T23:12:37.997293Z","steps":["trace[1373348118] 'agreement among raft nodes before linearized reading' (duration: 268.36239ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:38.148612Z","caller":"traceutil/trace.go:171","msg":"trace[560560054] transaction","detail":"{read_only:false; response_revision:27833; number_of_response:1; }","duration":"383.974494ms","start":"2026-04-28T23:12:37.764601Z","end":"2026-04-28T23:12:38.148575Z","steps":["trace[560560054] 'process raft request' (duration: 351.494333ms)","trace[560560054] 'compare' (duration: 32.094513ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:38.148641Z","caller":"traceutil/trace.go:171","msg":"trace[1828185115] linearizableReadLoop","detail":"{readStateIndex:29103; appliedIndex:29102; }","duration":"151.585473ms","start":"2026-04-28T23:12:37.997024Z","end":"2026-04-28T23:12:38.14861Z","steps":["trace[1828185115] 'read index received' (duration: 119.139264ms)","trace[1828185115] 'applied index is now lower than readState.Index' (duration: 32.444259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:38.148769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.764575Z","time spent":"384.112606ms","remote":"127.0.0.1:36592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:38.148826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.083485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-04-28T23:12:38.14873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.333009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:38.148881Z","caller":"traceutil/trace.go:171","msg":"trace[644523171] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27833; }","duration":"418.148427ms","start":"2026-04-28T23:12:37.730718Z","end":"2026-04-28T23:12:38.148867Z","steps":["trace[644523171] 'agreement among raft nodes before linearized reading' (duration: 418.025005ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:38.14889Z","caller":"traceutil/trace.go:171","msg":"trace[1212088416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27833; }","duration":"169.591514ms","start":"2026-04-28T23:12:37.979284Z","end":"2026-04-28T23:12:38.148875Z","steps":["trace[1212088416] 'agreement among raft nodes before linearized reading' (duration: 169.378569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:38.148905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:37.730711Z","time spent":"418.188688ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T23:12:38.149151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.248377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-517365a1-8217-407f-a4d9-952c45d9915d\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-04-28T23:12:38.149249Z","caller":"traceutil/trace.go:171","msg":"trace[2127907270] range","detail":"{range_begin:/registry/persistentvolumes/pvc-517365a1-8217-407f-a4d9-952c45d9915d; range_end:; response_count:1; response_revision:27833; }","duration":"148.384699ms","start":"2026-04-28T23:12:38.000813Z","end":"2026-04-28T23:12:38.149198Z","steps":["trace[2127907270] 'agreement among raft nodes before linearized reading' (duration: 148.067603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:38.486678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.145875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:6"} {"level":"info","ts":"2026-04-28T23:12:38.486788Z","caller":"traceutil/trace.go:171","msg":"trace[296749108] transaction","detail":"{read_only:false; number_of_response:0; response_revision:27834; }","duration":"330.594695ms","start":"2026-04-28T23:12:38.156178Z","end":"2026-04-28T23:12:38.486773Z","steps":["trace[296749108] 'process raft request' (duration: 162.290357ms)","trace[296749108] 'compare' (duration: 168.104284ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:38.486838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.156163Z","time spent":"330.654686ms","remote":"127.0.0.1:36592","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T23:12:38.705107Z","caller":"traceutil/trace.go:171","msg":"trace[657960290] transaction","detail":"{read_only:false; response_revision:27835; number_of_response:1; }","duration":"205.267968ms","start":"2026-04-28T23:12:38.499812Z","end":"2026-04-28T23:12:38.70508Z","steps":["trace[657960290] 'process raft request' (duration: 205.099224ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:38.908786Z","caller":"traceutil/trace.go:171","msg":"trace[1883610033] transaction","detail":"{read_only:false; response_revision:27836; number_of_response:1; }","duration":"352.32795ms","start":"2026-04-28T23:12:38.556422Z","end":"2026-04-28T23:12:38.90875Z","steps":["trace[1883610033] 'process raft request' (duration: 352.077205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:38.909021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.556395Z","time spent":"352.478654ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:39.315845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.161746ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T23:12:39.316097Z","caller":"traceutil/trace.go:171","msg":"trace[1360619011] linearizableReadLoop","detail":"{readStateIndex:29107; appliedIndex:29105; }","duration":"740.519447ms","start":"2026-04-28T23:12:38.575559Z","end":"2026-04-28T23:12:39.316079Z","steps":["trace[1360619011] 'read index received' (duration: 129.341378ms)","trace[1360619011] 'applied index is now lower than readState.Index' (duration: 611.176899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:39.316295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.713212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T23:12:39.31638Z","caller":"traceutil/trace.go:171","msg":"trace[2127310353] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:27836; }","duration":"740.846175ms","start":"2026-04-28T23:12:38.575509Z","end":"2026-04-28T23:12:39.316356Z","steps":["trace[2127310353] 'agreement among raft nodes before linearized reading' (duration: 740.61801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.316435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.575493Z","time spent":"740.926346ms","remote":"127.0.0.1:36314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":88,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-04-28T23:12:39.31644Z","caller":"traceutil/trace.go:171","msg":"trace[747672964] transaction","detail":"{read_only:false; response_revision:27837; number_of_response:1; }","duration":"607.764321ms","start":"2026-04-28T23:12:38.708651Z","end":"2026-04-28T23:12:39.316415Z","steps":["trace[747672964] 'process raft request' (duration: 607.325661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.316578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.708631Z","time spent":"607.874124ms","remote":"127.0.0.1:36386","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-04-28T23:12:39.316681Z","caller":"traceutil/trace.go:171","msg":"trace[444283437] transaction","detail":"{read_only:false; response_revision:27838; number_of_response:1; }","duration":"578.471636ms","start":"2026-04-28T23:12:38.738185Z","end":"2026-04-28T23:12:39.316656Z","steps":["trace[444283437] 'process raft request' (duration: 578.143879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.316866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.738168Z","time spent":"578.578978ms","remote":"127.0.0.1:36386","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-04-28T23:12:39.317346Z","caller":"traceutil/trace.go:171","msg":"trace[372701904] transaction","detail":"{read_only:false; response_revision:27840; number_of_response:1; }","duration":"399.28671ms","start":"2026-04-28T23:12:38.918046Z","end":"2026-04-28T23:12:39.317333Z","steps":["trace[372701904] 'process raft request' (duration: 399.179388ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:39.31734Z","caller":"traceutil/trace.go:171","msg":"trace[147782073] transaction","detail":"{read_only:false; response_revision:27839; number_of_response:1; }","duration":"579.005096ms","start":"2026-04-28T23:12:38.73831Z","end":"2026-04-28T23:12:39.317316Z","steps":["trace[147782073] 'process raft request' (duration: 578.257391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.317432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.918023Z","time spent":"399.369911ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:39.317478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.738291Z","time spent":"579.113268ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:39.317472Z","caller":"traceutil/trace.go:171","msg":"trace[1380902227] transaction","detail":"{read_only:false; response_revision:27841; number_of_response:1; }","duration":"229.856159ms","start":"2026-04-28T23:12:39.087588Z","end":"2026-04-28T23:12:39.317444Z","steps":["trace[1380902227] 'process raft request' (duration: 229.709476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.317533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.36988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:39.317578Z","caller":"traceutil/trace.go:171","msg":"trace[1052604948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27841; }","duration":"210.415491ms","start":"2026-04-28T23:12:39.10715Z","end":"2026-04-28T23:12:39.317566Z","steps":["trace[1052604948] 'agreement among raft nodes before linearized reading' (duration: 210.299618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.317596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.625958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11495"} {"level":"info","ts":"2026-04-28T23:12:39.317653Z","caller":"traceutil/trace.go:171","msg":"trace[1543632879] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27841; }","duration":"588.71646ms","start":"2026-04-28T23:12:38.728923Z","end":"2026-04-28T23:12:39.31764Z","steps":["trace[1543632879] 'agreement among raft nodes before linearized reading' (duration: 588.520086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.317685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.728906Z","time spent":"588.771121ms","remote":"127.0.0.1:36330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11518,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-28T23:12:39.317726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.982823ms","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-04-28T23:12:39.317809Z","caller":"traceutil/trace.go:171","msg":"trace[993500429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27841; }","duration":"394.098306ms","start":"2026-04-28T23:12:38.923681Z","end":"2026-04-28T23:12:39.31778Z","steps":["trace[993500429] 'agreement among raft nodes before linearized reading' (duration: 393.80304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.317865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:38.923661Z","time spent":"394.195057ms","remote":"127.0.0.1:36386","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":"info","ts":"2026-04-28T23:12:39.595643Z","caller":"traceutil/trace.go:171","msg":"trace[1679914875] linearizableReadLoop","detail":"{readStateIndex:29114; appliedIndex:29113; }","duration":"266.35542ms","start":"2026-04-28T23:12:39.329268Z","end":"2026-04-28T23:12:39.595624Z","steps":["trace[1679914875] 'read index received' (duration: 266.241617ms)","trace[1679914875] 'applied index is now lower than readState.Index' (duration: 112.723µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:39.595719Z","caller":"traceutil/trace.go:171","msg":"trace[778610716] transaction","detail":"{read_only:false; response_revision:27842; number_of_response:1; }","duration":"273.37135ms","start":"2026-04-28T23:12:39.322306Z","end":"2026-04-28T23:12:39.595677Z","steps":["trace[778610716] 'process raft request' (duration: 273.151295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.596052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.752827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-04-28T23:12:39.596136Z","caller":"traceutil/trace.go:171","msg":"trace[1163475212] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:27842; }","duration":"266.87919ms","start":"2026-04-28T23:12:39.329235Z","end":"2026-04-28T23:12:39.596114Z","steps":["trace[1163475212] 'agreement among raft nodes before linearized reading' (duration: 266.475592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.835129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.102524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:12:39.835297Z","caller":"traceutil/trace.go:171","msg":"trace[555030188] transaction","detail":"{read_only:false; response_revision:27844; number_of_response:1; }","duration":"102.070174ms","start":"2026-04-28T23:12:39.733214Z","end":"2026-04-28T23:12:39.835284Z","steps":["trace[555030188] 'process raft request' (duration: 101.997082ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:39.835277Z","caller":"traceutil/trace.go:171","msg":"trace[280130776] transaction","detail":"{read_only:false; response_revision:27843; number_of_response:1; }","duration":"441.931884ms","start":"2026-04-28T23:12:39.393305Z","end":"2026-04-28T23:12:39.835237Z","steps":["trace[280130776] 'process raft request' (duration: 232.651176ms)","trace[280130776] 'compare' (duration: 208.787068ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:39.835375Z","caller":"traceutil/trace.go:171","msg":"trace[36541145] linearizableReadLoop","detail":"{readStateIndex:29115; appliedIndex:29114; }","duration":"239.642366ms","start":"2026-04-28T23:12:39.595703Z","end":"2026-04-28T23:12:39.835345Z","steps":["trace[36541145] 'read index received' (duration: 30.178494ms)","trace[36541145] 'applied index is now lower than readState.Index' (duration: 209.461792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:39.835458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:39.39328Z","time spent":"442.080906ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:39.835658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.148786ms","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-04-28T23:12:39.83571Z","caller":"traceutil/trace.go:171","msg":"trace[1749134222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27844; }","duration":"424.243979ms","start":"2026-04-28T23:12:39.411451Z","end":"2026-04-28T23:12:39.835695Z","steps":["trace[1749134222] 'agreement among raft nodes before linearized reading' (duration: 424.063895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.835755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:39.411434Z","time spent":"424.30815ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T23:12:39.83578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.470874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-28T23:12:39.835805Z","caller":"traceutil/trace.go:171","msg":"trace[1840243845] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:27844; }","duration":"425.526515ms","start":"2026-04-28T23:12:39.410271Z","end":"2026-04-28T23:12:39.835797Z","steps":["trace[1840243845] 'agreement among raft nodes before linearized reading' (duration: 425.442993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.8358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.649575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T23:12:39.835792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.269706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-28T23:12:39.835822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:39.410251Z","time spent":"425.567816ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-04-28T23:12:39.835829Z","caller":"traceutil/trace.go:171","msg":"trace[333530786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27844; }","duration":"112.702425ms","start":"2026-04-28T23:12:39.72312Z","end":"2026-04-28T23:12:39.835822Z","steps":["trace[333530786] 'agreement among raft nodes before linearized reading' (duration: 112.603763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:39.835838Z","caller":"traceutil/trace.go:171","msg":"trace[2036612670] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27844; }","duration":"103.315517ms","start":"2026-04-28T23:12:39.732514Z","end":"2026-04-28T23:12:39.835829Z","steps":["trace[2036612670] 'agreement among raft nodes before linearized reading' (duration: 103.208415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:39.835921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.164365ms","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":"warn","ts":"2026-04-28T23:12:39.835926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.463371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-04-28T23:12:39.836017Z","caller":"traceutil/trace.go:171","msg":"trace[1441596265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27844; }","duration":"108.315638ms","start":"2026-04-28T23:12:39.727681Z","end":"2026-04-28T23:12:39.835997Z","steps":["trace[1441596265] 'agreement among raft nodes before linearized reading' (duration: 108.051232ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:39.836056Z","caller":"traceutil/trace.go:171","msg":"trace[1435437271] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27844; }","duration":"111.610903ms","start":"2026-04-28T23:12:39.724423Z","end":"2026-04-28T23:12:39.836034Z","steps":["trace[1435437271] 'agreement among raft nodes before linearized reading' (duration: 111.323958ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:40.092515Z","caller":"traceutil/trace.go:171","msg":"trace[328348919] transaction","detail":"{read_only:false; response_revision:27847; number_of_response:1; }","duration":"252.659926ms","start":"2026-04-28T23:12:39.839832Z","end":"2026-04-28T23:12:40.092492Z","steps":["trace[328348919] 'process raft request' (duration: 252.584075ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:40.092616Z","caller":"traceutil/trace.go:171","msg":"trace[852841469] transaction","detail":"{read_only:false; response_revision:27846; number_of_response:1; }","duration":"252.774179ms","start":"2026-04-28T23:12:39.839817Z","end":"2026-04-28T23:12:40.092591Z","steps":["trace[852841469] 'process raft request' (duration: 252.466253ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:40.092862Z","caller":"traceutil/trace.go:171","msg":"trace[1552148678] transaction","detail":"{read_only:false; response_revision:27849; number_of_response:1; }","duration":"252.958632ms","start":"2026-04-28T23:12:39.839892Z","end":"2026-04-28T23:12:40.09285Z","steps":["trace[1552148678] 'process raft request' (duration: 252.84898ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:40.092883Z","caller":"traceutil/trace.go:171","msg":"trace[1369845417] transaction","detail":"{read_only:false; response_revision:27848; number_of_response:1; }","duration":"252.953862ms","start":"2026-04-28T23:12:39.839885Z","end":"2026-04-28T23:12:40.092839Z","steps":["trace[1369845417] 'process raft request' (duration: 252.573115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:40.322656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.194625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:12:40.322733Z","caller":"traceutil/trace.go:171","msg":"trace[1590759063] linearizableReadLoop","detail":"{readStateIndex:29123; appliedIndex:29122; }","duration":"149.656145ms","start":"2026-04-28T23:12:40.173065Z","end":"2026-04-28T23:12:40.322721Z","steps":["trace[1590759063] 'read index received' (duration: 24.362897ms)","trace[1590759063] 'applied index is now lower than readState.Index' (duration: 125.292288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:40.322844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.805208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-04-28T23:12:40.32287Z","caller":"traceutil/trace.go:171","msg":"trace[1948296302] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27851; }","duration":"149.830729ms","start":"2026-04-28T23:12:40.173031Z","end":"2026-04-28T23:12:40.322862Z","steps":["trace[1948296302] 'agreement among raft nodes before linearized reading' (duration: 149.720637ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:40.322811Z","caller":"traceutil/trace.go:171","msg":"trace[384902151] transaction","detail":"{read_only:false; response_revision:27851; number_of_response:1; }","duration":"152.320218ms","start":"2026-04-28T23:12:40.170473Z","end":"2026-04-28T23:12:40.322793Z","steps":["trace[384902151] 'process raft request' (duration: 26.914459ms)","trace[384902151] 'compare' (duration: 124.983391ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:40.500509Z","caller":"traceutil/trace.go:171","msg":"trace[974635789] transaction","detail":"{read_only:false; response_revision:27852; number_of_response:1; }","duration":"170.625155ms","start":"2026-04-28T23:12:40.329861Z","end":"2026-04-28T23:12:40.500486Z","steps":["trace[974635789] 'process raft request' (duration: 148.018012ms)","trace[974635789] 'compare' (duration: 22.113833ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:41.494158Z","caller":"traceutil/trace.go:171","msg":"trace[1196802900] transaction","detail":"{read_only:false; response_revision:27858; number_of_response:1; }","duration":"170.080194ms","start":"2026-04-28T23:12:41.324057Z","end":"2026-04-28T23:12:41.494137Z","steps":["trace[1196802900] 'process raft request' (duration: 170.040833ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:41.494265Z","caller":"traceutil/trace.go:171","msg":"trace[649583304] transaction","detail":"{read_only:false; response_revision:27856; number_of_response:1; }","duration":"171.997001ms","start":"2026-04-28T23:12:41.322219Z","end":"2026-04-28T23:12:41.494216Z","steps":["trace[649583304] 'process raft request' (duration: 171.710386ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:41.494426Z","caller":"traceutil/trace.go:171","msg":"trace[733811354] transaction","detail":"{read_only:false; response_revision:27857; number_of_response:1; }","duration":"171.049662ms","start":"2026-04-28T23:12:41.323369Z","end":"2026-04-28T23:12:41.494418Z","steps":["trace[733811354] 'process raft request' (duration: 170.702005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:41.758151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.786407ms","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-04-28T23:12:41.758244Z","caller":"traceutil/trace.go:171","msg":"trace[1988350772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27858; }","duration":"157.93408ms","start":"2026-04-28T23:12:41.600288Z","end":"2026-04-28T23:12:41.758223Z","steps":["trace[1988350772] 'range keys from in-memory index tree' (duration: 157.537972ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:42.066262Z","caller":"traceutil/trace.go:171","msg":"trace[191988776] linearizableReadLoop","detail":"{readStateIndex:29132; appliedIndex:29131; }","duration":"211.095843ms","start":"2026-04-28T23:12:41.855142Z","end":"2026-04-28T23:12:42.066238Z","steps":["trace[191988776] 'read index received' (duration: 119.504301ms)","trace[191988776] 'applied index is now lower than readState.Index' (duration: 91.590632ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:42.066346Z","caller":"traceutil/trace.go:171","msg":"trace[905610011] transaction","detail":"{read_only:false; response_revision:27860; number_of_response:1; }","duration":"226.093683ms","start":"2026-04-28T23:12:41.840219Z","end":"2026-04-28T23:12:42.066312Z","steps":["trace[905610011] 'process raft request' (duration: 134.44582ms)","trace[905610011] 'compare' (duration: 91.458349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:42.066419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.257557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:42.066462Z","caller":"traceutil/trace.go:171","msg":"trace[876316792] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:27860; }","duration":"211.343268ms","start":"2026-04-28T23:12:41.855106Z","end":"2026-04-28T23:12:42.066449Z","steps":["trace[876316792] 'agreement among raft nodes before linearized reading' (duration: 211.259907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:42.066669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.096732ms","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-04-28T23:12:42.067059Z","caller":"traceutil/trace.go:171","msg":"trace[201505921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27860; }","duration":"142.254436ms","start":"2026-04-28T23:12:41.924503Z","end":"2026-04-28T23:12:42.066758Z","steps":["trace[201505921] 'agreement among raft nodes before linearized reading' (duration: 141.94666ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:47.928031Z","caller":"traceutil/trace.go:171","msg":"trace[1657976048] linearizableReadLoop","detail":"{readStateIndex:29185; appliedIndex:29184; }","duration":"105.495561ms","start":"2026-04-28T23:12:47.822508Z","end":"2026-04-28T23:12:47.928003Z","steps":["trace[1657976048] 'read index received' (duration: 105.185725ms)","trace[1657976048] 'applied index is now lower than readState.Index' (duration: 308.306µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:47.928108Z","caller":"traceutil/trace.go:171","msg":"trace[1339651650] transaction","detail":"{read_only:false; response_revision:27911; number_of_response:1; }","duration":"123.638794ms","start":"2026-04-28T23:12:47.804431Z","end":"2026-04-28T23:12:47.92807Z","steps":["trace[1339651650] 'process raft request' (duration: 123.345118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:47.928266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.720446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:47.92838Z","caller":"traceutil/trace.go:171","msg":"trace[679957278] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:27911; }","duration":"105.895639ms","start":"2026-04-28T23:12:47.822459Z","end":"2026-04-28T23:12:47.928354Z","steps":["trace[679957278] 'agreement among raft nodes before linearized reading' (duration: 105.656425ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:48.31895Z","caller":"traceutil/trace.go:171","msg":"trace[1991231351] linearizableReadLoop","detail":"{readStateIndex:29186; appliedIndex:29185; }","duration":"210.880779ms","start":"2026-04-28T23:12:48.108043Z","end":"2026-04-28T23:12:48.318924Z","steps":["trace[1991231351] 'read index received' (duration: 210.501132ms)","trace[1991231351] 'applied index is now lower than readState.Index' (duration: 378.647µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:48.319061Z","caller":"traceutil/trace.go:171","msg":"trace[1742183053] transaction","detail":"{read_only:false; response_revision:27912; number_of_response:1; }","duration":"222.607163ms","start":"2026-04-28T23:12:48.096431Z","end":"2026-04-28T23:12:48.319038Z","steps":["trace[1742183053] 'process raft request' (duration: 222.190885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.31917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.112805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:48.319228Z","caller":"traceutil/trace.go:171","msg":"trace[182485188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27912; }","duration":"211.218136ms","start":"2026-04-28T23:12:48.107994Z","end":"2026-04-28T23:12:48.319212Z","steps":["trace[182485188] 'agreement among raft nodes before linearized reading' (duration: 211.056203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.319262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.788287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T23:12:48.3193Z","caller":"traceutil/trace.go:171","msg":"trace[684636575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27912; }","duration":"179.878699ms","start":"2026-04-28T23:12:48.139409Z","end":"2026-04-28T23:12:48.319287Z","steps":["trace[684636575] 'agreement among raft nodes before linearized reading' (duration: 179.728746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.319343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.442381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-28T23:12:48.319391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.541512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-04-28T23:12:48.319426Z","caller":"traceutil/trace.go:171","msg":"trace[823528924] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27912; }","duration":"182.632384ms","start":"2026-04-28T23:12:48.136783Z","end":"2026-04-28T23:12:48.319415Z","steps":["trace[823528924] 'agreement among raft nodes before linearized reading' (duration: 182.525082ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:48.31942Z","caller":"traceutil/trace.go:171","msg":"trace[186054267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27912; }","duration":"207.555904ms","start":"2026-04-28T23:12:48.111843Z","end":"2026-04-28T23:12:48.319399Z","steps":["trace[186054267] 'agreement among raft nodes before linearized reading' (duration: 207.232117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.319439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.381039ms","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-04-28T23:12:48.319472Z","caller":"traceutil/trace.go:171","msg":"trace[90329026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27912; }","duration":"182.42742ms","start":"2026-04-28T23:12:48.137036Z","end":"2026-04-28T23:12:48.319463Z","steps":["trace[90329026] 'agreement among raft nodes before linearized reading' (duration: 182.118384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.31957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.868959ms","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-04-28T23:12:48.319658Z","caller":"traceutil/trace.go:171","msg":"trace[679290510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27912; }","duration":"187.996342ms","start":"2026-04-28T23:12:48.131634Z","end":"2026-04-28T23:12:48.319631Z","steps":["trace[679290510] 'agreement among raft nodes before linearized reading' (duration: 187.691326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.873607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.636605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:12:48.873869Z","caller":"traceutil/trace.go:171","msg":"trace[1909269873] transaction","detail":"{read_only:false; response_revision:27913; number_of_response:1; }","duration":"550.567086ms","start":"2026-04-28T23:12:48.323261Z","end":"2026-04-28T23:12:48.873828Z","steps":["trace[1909269873] 'process raft request' (duration: 285.615165ms)","trace[1909269873] 'compare' (duration: 262.637264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:48.874033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.323241Z","time spent":"550.695909ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:48.874184Z","caller":"traceutil/trace.go:171","msg":"trace[623371582] transaction","detail":"{read_only:false; response_revision:27915; number_of_response:1; }","duration":"550.27297ms","start":"2026-04-28T23:12:48.3239Z","end":"2026-04-28T23:12:48.874173Z","steps":["trace[623371582] 'process raft request' (duration: 550.079927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.874248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.323881Z","time spent":"550.334981ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:48.874265Z","caller":"traceutil/trace.go:171","msg":"trace[1417297320] transaction","detail":"{read_only:false; response_revision:27916; number_of_response:1; }","duration":"549.824231ms","start":"2026-04-28T23:12:48.324398Z","end":"2026-04-28T23:12:48.874223Z","steps":["trace[1417297320] 'process raft request' (duration: 549.619597ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:48.874337Z","caller":"traceutil/trace.go:171","msg":"trace[1298934981] transaction","detail":"{read_only:false; response_revision:27914; number_of_response:1; }","duration":"551.064486ms","start":"2026-04-28T23:12:48.323263Z","end":"2026-04-28T23:12:48.874327Z","steps":["trace[1298934981] 'process raft request' (duration: 550.451373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.874394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.323245Z","time spent":"551.121387ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:48.874437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.324391Z","time spent":"549.958094ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:48.874476Z","caller":"traceutil/trace.go:171","msg":"trace[1743659340] transaction","detail":"{read_only:false; response_revision:27917; number_of_response:1; }","duration":"550.046957ms","start":"2026-04-28T23:12:48.324419Z","end":"2026-04-28T23:12:48.874466Z","steps":["trace[1743659340] 'process raft request' (duration: 549.635438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:48.87453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.324411Z","time spent":"550.091858ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:49.185561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.445353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T23:12:49.185662Z","caller":"traceutil/trace.go:171","msg":"trace[357572130] linearizableReadLoop","detail":"{readStateIndex:29192; appliedIndex:29186; }","duration":"799.929917ms","start":"2026-04-28T23:12:48.385714Z","end":"2026-04-28T23:12:49.185644Z","steps":["trace[357572130] 'read index received' (duration: 223.172666ms)","trace[357572130] 'applied index is now lower than readState.Index' (duration: 576.755541ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:49.1862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.478038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-04-28T23:12:49.186251Z","caller":"traceutil/trace.go:171","msg":"trace[423613100] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:27917; }","duration":"800.57789ms","start":"2026-04-28T23:12:48.385657Z","end":"2026-04-28T23:12:49.186235Z","steps":["trace[423613100] 'agreement among raft nodes before linearized reading' (duration: 800.399546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:49.186291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.385636Z","time spent":"800.647182ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":685,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T23:12:49.186405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.857095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:12:49.186494Z","caller":"traceutil/trace.go:171","msg":"trace[853100009] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:27917; }","duration":"758.005548ms","start":"2026-04-28T23:12:48.428469Z","end":"2026-04-28T23:12:49.186475Z","steps":["trace[853100009] 'agreement among raft nodes before linearized reading' (duration: 757.863186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:49.186516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.165649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-04-28T23:12:49.186544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.428446Z","time spent":"758.0877ms","remote":"127.0.0.1:58986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"info","ts":"2026-04-28T23:12:49.18657Z","caller":"traceutil/trace.go:171","msg":"trace[342985384] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27917; }","duration":"565.252241ms","start":"2026-04-28T23:12:48.6213Z","end":"2026-04-28T23:12:49.186552Z","steps":["trace[342985384] 'agreement among raft nodes before linearized reading' (duration: 565.085828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:49.186606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:48.62128Z","time spent":"565.318282ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":670,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T23:12:49.509226Z","caller":"traceutil/trace.go:171","msg":"trace[185638557] transaction","detail":"{read_only:false; response_revision:27918; number_of_response:1; }","duration":"314.944412ms","start":"2026-04-28T23:12:49.194255Z","end":"2026-04-28T23:12:49.509199Z","steps":["trace[185638557] 'process raft request' (duration: 314.750358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:49.509393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:49.194238Z","time spent":"315.067314ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:49.511054Z","caller":"traceutil/trace.go:171","msg":"trace[1260976508] linearizableReadLoop","detail":"{readStateIndex:29194; appliedIndex:29193; }","duration":"215.86738ms","start":"2026-04-28T23:12:49.295164Z","end":"2026-04-28T23:12:49.511032Z","steps":["trace[1260976508] 'read index received' (duration: 214.744507ms)","trace[1260976508] 'applied index is now lower than readState.Index' (duration: 1.121393ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:49.511097Z","caller":"traceutil/trace.go:171","msg":"trace[706808063] transaction","detail":"{read_only:false; response_revision:27920; number_of_response:1; }","duration":"140.228156ms","start":"2026-04-28T23:12:49.370857Z","end":"2026-04-28T23:12:49.511085Z","steps":["trace[706808063] 'process raft request' (duration: 140.075993ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:49.511135Z","caller":"traceutil/trace.go:171","msg":"trace[1949293729] transaction","detail":"{read_only:false; response_revision:27919; number_of_response:1; }","duration":"316.651547ms","start":"2026-04-28T23:12:49.194477Z","end":"2026-04-28T23:12:49.511128Z","steps":["trace[1949293729] 'process raft request' (duration: 316.32289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:49.511183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:49.194467Z","time spent":"316.687027ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:49.5113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.130125ms","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-04-28T23:12:49.511361Z","caller":"traceutil/trace.go:171","msg":"trace[1063949769] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:27920; }","duration":"216.246957ms","start":"2026-04-28T23:12:49.295099Z","end":"2026-04-28T23:12:49.511346Z","steps":["trace[1063949769] 'agreement among raft nodes before linearized reading' (duration: 216.123715ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:53.063203Z","caller":"traceutil/trace.go:171","msg":"trace[1528151456] linearizableReadLoop","detail":"{readStateIndex:29227; appliedIndex:29226; }","duration":"142.028591ms","start":"2026-04-28T23:12:52.921146Z","end":"2026-04-28T23:12:53.063174Z","steps":["trace[1528151456] 'read index received' (duration: 141.855368ms)","trace[1528151456] 'applied index is now lower than readState.Index' (duration: 172.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:53.063339Z","caller":"traceutil/trace.go:171","msg":"trace[307809705] transaction","detail":"{read_only:false; response_revision:27950; number_of_response:1; }","duration":"303.331928ms","start":"2026-04-28T23:12:52.759992Z","end":"2026-04-28T23:12:53.063324Z","steps":["trace[307809705] 'process raft request' (duration: 303.010642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:53.063456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:52.759943Z","time spent":"303.42949ms","remote":"127.0.0.1:54262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2569,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T23:12:53.063667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.824686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-28T23:12:53.063726Z","caller":"traceutil/trace.go:171","msg":"trace[1289018705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27950; }","duration":"116.890787ms","start":"2026-04-28T23:12:52.946817Z","end":"2026-04-28T23:12:53.063707Z","steps":["trace[1289018705] 'agreement among raft nodes before linearized reading' (duration: 116.701924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:53.063724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.860647ms","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-04-28T23:12:53.063751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.591112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-28T23:12:53.063784Z","caller":"traceutil/trace.go:171","msg":"trace[497317779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27950; }","duration":"116.96131ms","start":"2026-04-28T23:12:52.946808Z","end":"2026-04-28T23:12:53.06377Z","steps":["trace[497317779] 'agreement among raft nodes before linearized reading' (duration: 116.694324ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:53.063812Z","caller":"traceutil/trace.go:171","msg":"trace[168657317] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:27950; }","duration":"142.700934ms","start":"2026-04-28T23:12:52.921099Z","end":"2026-04-28T23:12:53.0638Z","steps":["trace[168657317] 'agreement among raft nodes before linearized reading' (duration: 142.47624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:53.063707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.930659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-28T23:12:53.063983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.145433ms","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-04-28T23:12:53.064034Z","caller":"traceutil/trace.go:171","msg":"trace[1802925015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27950; }","duration":"117.233585ms","start":"2026-04-28T23:12:52.94679Z","end":"2026-04-28T23:12:53.064023Z","steps":["trace[1802925015] 'agreement among raft nodes before linearized reading' (duration: 117.082452ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:53.064019Z","caller":"traceutil/trace.go:171","msg":"trace[1924837991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27950; }","duration":"117.201734ms","start":"2026-04-28T23:12:52.946725Z","end":"2026-04-28T23:12:53.063926Z","steps":["trace[1924837991] 'agreement among raft nodes before linearized reading' (duration: 116.770885ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:54.833334Z","caller":"traceutil/trace.go:171","msg":"trace[1290561471] linearizableReadLoop","detail":"{readStateIndex:29320; appliedIndex:29319; }","duration":"148.43262ms","start":"2026-04-28T23:12:54.684881Z","end":"2026-04-28T23:12:54.833314Z","steps":["trace[1290561471] 'read index received' (duration: 148.212556ms)","trace[1290561471] 'applied index is now lower than readState.Index' (duration: 219.224µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:54.833456Z","caller":"traceutil/trace.go:171","msg":"trace[959419975] transaction","detail":"{read_only:false; response_revision:28041; number_of_response:1; }","duration":"161.716955ms","start":"2026-04-28T23:12:54.671726Z","end":"2026-04-28T23:12:54.833443Z","steps":["trace[959419975] 'process raft request' (duration: 161.42663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:54.834044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.114294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-04-28T23:12:54.834202Z","caller":"traceutil/trace.go:171","msg":"trace[1008731883] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:28041; }","duration":"149.383789ms","start":"2026-04-28T23:12:54.684797Z","end":"2026-04-28T23:12:54.834181Z","steps":["trace[1008731883] 'agreement among raft nodes before linearized reading' (duration: 148.93791ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.133161Z","caller":"traceutil/trace.go:171","msg":"trace[1501656054] linearizableReadLoop","detail":"{readStateIndex:29321; appliedIndex:29320; }","duration":"274.607205ms","start":"2026-04-28T23:12:54.858524Z","end":"2026-04-28T23:12:55.133131Z","steps":["trace[1501656054] 'read index received' (duration: 235.310079ms)","trace[1501656054] 'applied index is now lower than readState.Index' (duration: 39.295846ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:55.133283Z","caller":"traceutil/trace.go:171","msg":"trace[500520544] transaction","detail":"{read_only:false; response_revision:28042; number_of_response:1; }","duration":"289.416042ms","start":"2026-04-28T23:12:54.843851Z","end":"2026-04-28T23:12:55.133267Z","steps":["trace[500520544] 'process raft request' (duration: 249.946412ms)","trace[500520544] 'compare' (duration: 39.194995ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:12:55.133542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.001413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11495"} {"level":"info","ts":"2026-04-28T23:12:55.133569Z","caller":"traceutil/trace.go:171","msg":"trace[469715322] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28042; }","duration":"275.092165ms","start":"2026-04-28T23:12:54.858468Z","end":"2026-04-28T23:12:55.13356Z","steps":["trace[469715322] 'agreement among raft nodes before linearized reading' (duration: 274.932212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.141329Z","caller":"traceutil/trace.go:171","msg":"trace[668780820] transaction","detail":"{read_only:false; response_revision:28043; number_of_response:1; }","duration":"270.711947ms","start":"2026-04-28T23:12:54.870596Z","end":"2026-04-28T23:12:55.141308Z","steps":["trace[668780820] 'process raft request' (duration: 270.562924ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.336561Z","caller":"traceutil/trace.go:171","msg":"trace[543401668] transaction","detail":"{read_only:false; response_revision:28044; number_of_response:1; }","duration":"191.121554ms","start":"2026-04-28T23:12:55.145412Z","end":"2026-04-28T23:12:55.336533Z","steps":["trace[543401668] 'process raft request' (duration: 190.958231ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.473474Z","caller":"traceutil/trace.go:171","msg":"trace[1559316725] linearizableReadLoop","detail":"{readStateIndex:29327; appliedIndex:29322; }","duration":"195.87405ms","start":"2026-04-28T23:12:55.277573Z","end":"2026-04-28T23:12:55.473447Z","steps":["trace[1559316725] 'read index received' (duration: 58.801947ms)","trace[1559316725] 'applied index is now lower than readState.Index' (duration: 137.071193ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:12:55.473612Z","caller":"traceutil/trace.go:171","msg":"trace[1957322982] transaction","detail":"{read_only:false; response_revision:28047; number_of_response:1; }","duration":"325.930862ms","start":"2026-04-28T23:12:55.147626Z","end":"2026-04-28T23:12:55.473556Z","steps":["trace[1957322982] 'process raft request' (duration: 325.639786ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.47365Z","caller":"traceutil/trace.go:171","msg":"trace[217882150] transaction","detail":"{read_only:false; response_revision:28048; number_of_response:1; }","duration":"284.690617ms","start":"2026-04-28T23:12:55.18891Z","end":"2026-04-28T23:12:55.473601Z","steps":["trace[217882150] 'process raft request' (duration: 284.460882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:55.473815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.201116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T23:12:55.473938Z","caller":"traceutil/trace.go:171","msg":"trace[789944859] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:28048; }","duration":"196.39503ms","start":"2026-04-28T23:12:55.277509Z","end":"2026-04-28T23:12:55.473904Z","steps":["trace[789944859] 'agreement among raft nodes before linearized reading' (duration: 196.120684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:55.473971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:55.147616Z","time spent":"326.170696ms","remote":"127.0.0.1:54262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:55.474653Z","caller":"traceutil/trace.go:171","msg":"trace[1297203994] transaction","detail":"{read_only:false; response_revision:28046; number_of_response:1; }","duration":"326.856801ms","start":"2026-04-28T23:12:55.146544Z","end":"2026-04-28T23:12:55.4734Z","steps":["trace[1297203994] 'process raft request' (duration: 326.666737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:55.474796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:55.146532Z","time spent":"328.193638ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:55.475323Z","caller":"traceutil/trace.go:171","msg":"trace[1214752552] transaction","detail":"{read_only:false; response_revision:28045; number_of_response:1; }","duration":"329.561674ms","start":"2026-04-28T23:12:55.14571Z","end":"2026-04-28T23:12:55.475271Z","steps":["trace[1214752552] 'process raft request' (duration: 327.298669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:12:55.475444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:12:55.145692Z","time spent":"329.671497ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:12:55.583825Z","caller":"traceutil/trace.go:171","msg":"trace[409589927] transaction","detail":"{read_only:false; response_revision:28049; number_of_response:1; }","duration":"105.163405ms","start":"2026-04-28T23:12:55.478631Z","end":"2026-04-28T23:12:55.583795Z","steps":["trace[409589927] 'process raft request' (duration: 104.034092ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.640856Z","caller":"traceutil/trace.go:171","msg":"trace[715050605] transaction","detail":"{read_only:false; response_revision:28050; number_of_response:1; }","duration":"161.816078ms","start":"2026-04-28T23:12:55.479008Z","end":"2026-04-28T23:12:55.640824Z","steps":["trace[715050605] 'process raft request' (duration: 161.501022ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.641036Z","caller":"traceutil/trace.go:171","msg":"trace[935241936] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28050; }","duration":"157.781597ms","start":"2026-04-28T23:12:55.483233Z","end":"2026-04-28T23:12:55.641015Z","steps":["trace[935241936] 'process raft request' (duration: 157.469421ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.641251Z","caller":"traceutil/trace.go:171","msg":"trace[1117508949] transaction","detail":"{read_only:false; response_revision:28051; number_of_response:1; }","duration":"157.877789ms","start":"2026-04-28T23:12:55.483368Z","end":"2026-04-28T23:12:55.641245Z","steps":["trace[1117508949] 'process raft request' (duration: 157.3762ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:12:55.641091Z","caller":"traceutil/trace.go:171","msg":"trace[681830007] transaction","detail":"{read_only:false; response_revision:28052; number_of_response:1; }","duration":"120.597993ms","start":"2026-04-28T23:12:55.520487Z","end":"2026-04-28T23:12:55.641085Z","steps":["trace[681830007] 'process raft request' (duration: 120.343508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:02.076808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.667694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10483"} {"level":"info","ts":"2026-04-28T23:13:02.076922Z","caller":"traceutil/trace.go:171","msg":"trace[2006192891] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28144; }","duration":"136.831547ms","start":"2026-04-28T23:13:01.940065Z","end":"2026-04-28T23:13:02.076897Z","steps":["trace[2006192891] 'range keys from in-memory index tree' (duration: 136.44376ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:02.228805Z","caller":"traceutil/trace.go:171","msg":"trace[1182431894] transaction","detail":"{read_only:false; response_revision:28145; number_of_response:1; }","duration":"146.339999ms","start":"2026-04-28T23:13:02.08244Z","end":"2026-04-28T23:13:02.22878Z","steps":["trace[1182431894] 'process raft request' (duration: 146.195026ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:02.513655Z","caller":"traceutil/trace.go:171","msg":"trace[80488803] linearizableReadLoop","detail":"{readStateIndex:29429; appliedIndex:29428; }","duration":"283.911991ms","start":"2026-04-28T23:13:02.229716Z","end":"2026-04-28T23:13:02.513628Z","steps":["trace[80488803] 'read index received' (duration: 283.782029ms)","trace[80488803] 'applied index is now lower than readState.Index' (duration: 128.692µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:02.513823Z","caller":"traceutil/trace.go:171","msg":"trace[1091824885] transaction","detail":"{read_only:false; response_revision:28146; number_of_response:1; }","duration":"426.195038ms","start":"2026-04-28T23:13:02.087583Z","end":"2026-04-28T23:13:02.513778Z","steps":["trace[1091824885] 'process raft request' (duration: 425.861992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:02.513849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.096535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-28T23:13:02.514007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:02.087556Z","time spent":"426.364712ms","remote":"127.0.0.1:36330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10758,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:13:02.514043Z","caller":"traceutil/trace.go:171","msg":"trace[1188926487] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:28146; }","duration":"284.3471ms","start":"2026-04-28T23:13:02.22968Z","end":"2026-04-28T23:13:02.514027Z","steps":["trace[1188926487] 'agreement among raft nodes before linearized reading' (duration: 284.074865ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:02.544709Z","caller":"traceutil/trace.go:171","msg":"trace[140960693] transaction","detail":"{read_only:false; response_revision:28147; number_of_response:1; }","duration":"165.826559ms","start":"2026-04-28T23:13:02.378859Z","end":"2026-04-28T23:13:02.544685Z","steps":["trace[140960693] 'process raft request' (duration: 165.667395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:02.544725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.214816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:13:02.544783Z","caller":"traceutil/trace.go:171","msg":"trace[757657314] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:28147; }","duration":"157.334698ms","start":"2026-04-28T23:13:02.387434Z","end":"2026-04-28T23:13:02.544768Z","steps":["trace[757657314] 'agreement among raft nodes before linearized reading' (duration: 157.212696ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:02.709917Z","caller":"traceutil/trace.go:171","msg":"trace[1200861327] transaction","detail":"{read_only:false; response_revision:28148; number_of_response:1; }","duration":"187.562413ms","start":"2026-04-28T23:13:02.522318Z","end":"2026-04-28T23:13:02.70988Z","steps":["trace[1200861327] 'process raft request' (duration: 182.611005ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:02.709971Z","caller":"traceutil/trace.go:171","msg":"trace[1749279282] transaction","detail":"{read_only:false; response_revision:28149; number_of_response:1; }","duration":"187.598304ms","start":"2026-04-28T23:13:02.522352Z","end":"2026-04-28T23:13:02.709951Z","steps":["trace[1749279282] 'process raft request' (duration: 187.468762ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:02.71043Z","caller":"traceutil/trace.go:171","msg":"trace[661149158] transaction","detail":"{read_only:false; response_revision:28150; number_of_response:1; }","duration":"187.776287ms","start":"2026-04-28T23:13:02.52263Z","end":"2026-04-28T23:13:02.710406Z","steps":["trace[661149158] 'process raft request' (duration: 187.287917ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:02.710687Z","caller":"traceutil/trace.go:171","msg":"trace[140241166] transaction","detail":"{read_only:false; response_revision:28151; number_of_response:1; }","duration":"187.963441ms","start":"2026-04-28T23:13:02.522713Z","end":"2026-04-28T23:13:02.710676Z","steps":["trace[140241166] 'process raft request' (duration: 187.48815ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:31.41353Z","caller":"traceutil/trace.go:171","msg":"trace[1116173906] transaction","detail":"{read_only:false; response_revision:28417; number_of_response:1; }","duration":"112.212535ms","start":"2026-04-28T23:13:31.301292Z","end":"2026-04-28T23:13:31.413504Z","steps":["trace[1116173906] 'process raft request' (duration: 111.95718ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:31.715117Z","caller":"traceutil/trace.go:171","msg":"trace[2007611646] transaction","detail":"{read_only:false; response_revision:28419; number_of_response:1; }","duration":"135.664704ms","start":"2026-04-28T23:13:31.579427Z","end":"2026-04-28T23:13:31.715092Z","steps":["trace[2007611646] 'process raft request' (duration: 134.071803ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:33.884542Z","caller":"traceutil/trace.go:171","msg":"trace[1534870160] transaction","detail":"{read_only:false; response_revision:28437; number_of_response:1; }","duration":"159.633094ms","start":"2026-04-28T23:13:33.724882Z","end":"2026-04-28T23:13:33.884515Z","steps":["trace[1534870160] 'process raft request' (duration: 159.43972ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:33.971024Z","caller":"traceutil/trace.go:171","msg":"trace[30599621] linearizableReadLoop","detail":"{readStateIndex:29735; appliedIndex:29732; }","duration":"165.045502ms","start":"2026-04-28T23:13:33.805953Z","end":"2026-04-28T23:13:33.970998Z","steps":["trace[30599621] 'read index received' (duration: 78.439439ms)","trace[30599621] 'applied index is now lower than readState.Index' (duration: 86.604903ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:33.971043Z","caller":"traceutil/trace.go:171","msg":"trace[1560464466] transaction","detail":"{read_only:false; response_revision:28438; number_of_response:1; }","duration":"168.812668ms","start":"2026-04-28T23:13:33.802187Z","end":"2026-04-28T23:13:33.971Z","steps":["trace[1560464466] 'process raft request' (duration: 167.472521ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:33.97105Z","caller":"traceutil/trace.go:171","msg":"trace[1356065942] transaction","detail":"{read_only:false; response_revision:28439; number_of_response:1; }","duration":"168.047162ms","start":"2026-04-28T23:13:33.802955Z","end":"2026-04-28T23:13:33.971002Z","steps":["trace[1356065942] 'process raft request' (duration: 167.95289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:33.971281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.304837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-28T23:13:33.971327Z","caller":"traceutil/trace.go:171","msg":"trace[689209510] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28439; }","duration":"165.396789ms","start":"2026-04-28T23:13:33.805917Z","end":"2026-04-28T23:13:33.971314Z","steps":["trace[689209510] 'agreement among raft nodes before linearized reading' (duration: 165.218556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:33.971767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.183985ms","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-04-28T23:13:33.971768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.639964ms","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-04-28T23:13:33.971821Z","caller":"traceutil/trace.go:171","msg":"trace[1135426615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28441; }","duration":"123.312257ms","start":"2026-04-28T23:13:33.848495Z","end":"2026-04-28T23:13:33.971807Z","steps":["trace[1135426615] 'agreement among raft nodes before linearized reading' (duration: 123.125783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:33.971765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.287927ms","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-04-28T23:13:33.971866Z","caller":"traceutil/trace.go:171","msg":"trace[1252331336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28441; }","duration":"112.782077ms","start":"2026-04-28T23:13:33.85906Z","end":"2026-04-28T23:13:33.971842Z","steps":["trace[1252331336] 'agreement among raft nodes before linearized reading' (duration: 112.540262ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:33.971925Z","caller":"traceutil/trace.go:171","msg":"trace[158179250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28441; }","duration":"144.502012ms","start":"2026-04-28T23:13:33.827407Z","end":"2026-04-28T23:13:33.971909Z","steps":["trace[158179250] 'agreement among raft nodes before linearized reading' (duration: 144.139515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:34.25931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.9631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:13:34.259445Z","caller":"traceutil/trace.go:171","msg":"trace[708161866] transaction","detail":"{read_only:false; response_revision:28442; number_of_response:1; }","duration":"282.85068ms","start":"2026-04-28T23:13:33.976557Z","end":"2026-04-28T23:13:34.259407Z","steps":["trace[708161866] 'process raft request' (duration: 142.703465ms)","trace[708161866] 'compare' (duration: 139.841538ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:34.271738Z","caller":"traceutil/trace.go:171","msg":"trace[801613116] transaction","detail":"{read_only:false; response_revision:28444; number_of_response:1; }","duration":"293.995443ms","start":"2026-04-28T23:13:33.977721Z","end":"2026-04-28T23:13:34.271716Z","steps":["trace[801613116] 'process raft request' (duration: 293.932102ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:34.271872Z","caller":"traceutil/trace.go:171","msg":"trace[1540690338] linearizableReadLoop","detail":"{readStateIndex:29741; appliedIndex:29737; }","duration":"163.956521ms","start":"2026-04-28T23:13:34.107897Z","end":"2026-04-28T23:13:34.271854Z","steps":["trace[1540690338] 'read index received' (duration: 10.157003ms)","trace[1540690338] 'applied index is now lower than readState.Index' (duration: 153.798698ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:34.271986Z","caller":"traceutil/trace.go:171","msg":"trace[654254067] transaction","detail":"{read_only:false; response_revision:28443; number_of_response:1; }","duration":"295.163056ms","start":"2026-04-28T23:13:33.976792Z","end":"2026-04-28T23:13:34.271955Z","steps":["trace[654254067] 'process raft request' (duration: 294.745958ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:34.272226Z","caller":"traceutil/trace.go:171","msg":"trace[1134030846] transaction","detail":"{read_only:false; response_revision:28445; number_of_response:1; }","duration":"294.092325ms","start":"2026-04-28T23:13:33.978114Z","end":"2026-04-28T23:13:34.272207Z","steps":["trace[1134030846] 'process raft request' (duration: 293.568925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:34.273027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.074143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:13:34.273067Z","caller":"traceutil/trace.go:171","msg":"trace[267561097] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28445; }","duration":"165.236147ms","start":"2026-04-28T23:13:34.10782Z","end":"2026-04-28T23:13:34.273057Z","steps":["trace[267561097] 'agreement among raft nodes before linearized reading' (duration: 164.098214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:34.486034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.820237ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:13:34.486283Z","caller":"traceutil/trace.go:171","msg":"trace[1997404973] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:28445; }","duration":"112.091763ms","start":"2026-04-28T23:13:34.374171Z","end":"2026-04-28T23:13:34.486263Z","steps":["trace[1997404973] 'range keys from in-memory index tree' (duration: 111.798636ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:34.486352Z","caller":"traceutil/trace.go:171","msg":"trace[2036977232] linearizableReadLoop","detail":"{readStateIndex:29742; appliedIndex:29741; }","duration":"203.625645ms","start":"2026-04-28T23:13:34.282709Z","end":"2026-04-28T23:13:34.486335Z","steps":["trace[2036977232] 'read index received' (duration: 136.524122ms)","trace[2036977232] 'applied index is now lower than readState.Index' (duration: 67.100023ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:34.486433Z","caller":"traceutil/trace.go:171","msg":"trace[217679428] transaction","detail":"{read_only:false; response_revision:28446; number_of_response:1; }","duration":"204.815519ms","start":"2026-04-28T23:13:34.281572Z","end":"2026-04-28T23:13:34.486387Z","steps":["trace[217679428] 'process raft request' (duration: 137.770688ms)","trace[217679428] 'compare' (duration: 66.863108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:34.486621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.87952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-28T23:13:34.486722Z","caller":"traceutil/trace.go:171","msg":"trace[73104475] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28446; }","duration":"204.011992ms","start":"2026-04-28T23:13:34.282679Z","end":"2026-04-28T23:13:34.486691Z","steps":["trace[73104475] 'agreement among raft nodes before linearized reading' (duration: 203.721497ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:34.592816Z","caller":"traceutil/trace.go:171","msg":"trace[534263990] transaction","detail":"{read_only:false; response_revision:28448; number_of_response:1; }","duration":"100.556732ms","start":"2026-04-28T23:13:34.492229Z","end":"2026-04-28T23:13:34.592786Z","steps":["trace[534263990] 'process raft request' (duration: 100.01323ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:38.450241Z","caller":"traceutil/trace.go:171","msg":"trace[1810664326] transaction","detail":"{read_only:false; response_revision:28478; number_of_response:1; }","duration":"146.962161ms","start":"2026-04-28T23:13:38.303255Z","end":"2026-04-28T23:13:38.450217Z","steps":["trace[1810664326] 'process raft request' (duration: 146.921831ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:38.450503Z","caller":"traceutil/trace.go:171","msg":"trace[623420803] transaction","detail":"{read_only:false; response_revision:28476; number_of_response:1; }","duration":"148.815968ms","start":"2026-04-28T23:13:38.301672Z","end":"2026-04-28T23:13:38.450488Z","steps":["trace[623420803] 'process raft request' (duration: 148.324357ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:38.450617Z","caller":"traceutil/trace.go:171","msg":"trace[360954913] transaction","detail":"{read_only:false; response_revision:28477; number_of_response:1; }","duration":"147.41172ms","start":"2026-04-28T23:13:38.303189Z","end":"2026-04-28T23:13:38.4506Z","steps":["trace[360954913] 'process raft request' (duration: 146.949001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:38.909798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.851251ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-28T23:13:38.910154Z","caller":"traceutil/trace.go:171","msg":"trace[2049320764] linearizableReadLoop","detail":"{readStateIndex:29776; appliedIndex:29775; }","duration":"342.367151ms","start":"2026-04-28T23:13:38.567768Z","end":"2026-04-28T23:13:38.910135Z","steps":["trace[2049320764] 'read index received' (duration: 16.099082ms)","trace[2049320764] 'applied index is now lower than readState.Index' (duration: 326.266899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:38.910334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.564675ms","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-04-28T23:13:38.910365Z","caller":"traceutil/trace.go:171","msg":"trace[197120913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28478; }","duration":"342.647626ms","start":"2026-04-28T23:13:38.567706Z","end":"2026-04-28T23:13:38.910354Z","steps":["trace[197120913] 'agreement among raft nodes before linearized reading' (duration: 342.532694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:38.910385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:38.567689Z","time spent":"342.691538ms","remote":"127.0.0.1:36386","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":"info","ts":"2026-04-28T23:13:38.910385Z","caller":"traceutil/trace.go:171","msg":"trace[1113695917] transaction","detail":"{read_only:false; response_revision:28479; number_of_response:1; }","duration":"204.820468ms","start":"2026-04-28T23:13:38.705553Z","end":"2026-04-28T23:13:38.910373Z","steps":["trace[1113695917] 'process raft request' (duration: 204.358669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:39.046121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.201688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-04-28T23:13:39.046191Z","caller":"traceutil/trace.go:171","msg":"trace[230986821] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28479; }","duration":"423.28291ms","start":"2026-04-28T23:13:38.622892Z","end":"2026-04-28T23:13:39.046175Z","steps":["trace[230986821] 'agreement among raft nodes before linearized reading' (duration: 287.460582ms)","trace[230986821] 'range keys from in-memory index tree' (duration: 135.658214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:39.046216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:38.622843Z","time spent":"423.367191ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":685,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T23:13:39.046406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.741546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:13:39.046429Z","caller":"traceutil/trace.go:171","msg":"trace[1787103085] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:28479; }","duration":"199.790147ms","start":"2026-04-28T23:13:38.846631Z","end":"2026-04-28T23:13:39.046421Z","steps":["trace[1787103085] 'agreement among raft nodes before linearized reading' (duration: 63.778087ms)","trace[1787103085] 'count revisions from in-memory index tree' (duration: 135.95695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:39.046527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.309999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-04-28T23:13:39.046548Z","caller":"traceutil/trace.go:171","msg":"trace[1203437794] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28479; }","duration":"419.342101ms","start":"2026-04-28T23:13:38.627198Z","end":"2026-04-28T23:13:39.046541Z","steps":["trace[1203437794] 'agreement among raft nodes before linearized reading' (duration: 283.259228ms)","trace[1203437794] 'range keys from in-memory index tree' (duration: 136.034451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:39.04656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:38.627192Z","time spent":"419.365301ms","remote":"127.0.0.1:36250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":670,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-28T23:13:39.047559Z","caller":"traceutil/trace.go:171","msg":"trace[2046986530] transaction","detail":"{read_only:false; response_revision:28480; number_of_response:1; }","duration":"130.751876ms","start":"2026-04-28T23:13:38.916779Z","end":"2026-04-28T23:13:39.047531Z","steps":["trace[2046986530] 'process raft request' (duration: 71.48139ms)","trace[2046986530] 'compare' (duration: 59.149894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:39.31731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.459851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:13:39.317398Z","caller":"traceutil/trace.go:171","msg":"trace[2038780488] transaction","detail":"{read_only:false; response_revision:28481; number_of_response:1; }","duration":"263.476732ms","start":"2026-04-28T23:13:39.053905Z","end":"2026-04-28T23:13:39.317381Z","steps":["trace[2038780488] 'process raft request' (duration: 160.878089ms)","trace[2038780488] 'compare' (duration: 102.235606ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:39.324022Z","caller":"traceutil/trace.go:171","msg":"trace[1886504666] transaction","detail":"{read_only:false; response_revision:28482; number_of_response:1; }","duration":"268.220967ms","start":"2026-04-28T23:13:39.055778Z","end":"2026-04-28T23:13:39.323999Z","steps":["trace[1886504666] 'process raft request' (duration: 267.961482ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:39.3244Z","caller":"traceutil/trace.go:171","msg":"trace[524411875] transaction","detail":"{read_only:false; response_revision:28483; number_of_response:1; }","duration":"255.34334ms","start":"2026-04-28T23:13:39.069046Z","end":"2026-04-28T23:13:39.324389Z","steps":["trace[524411875] 'process raft request' (duration: 254.86775ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:39.553872Z","caller":"traceutil/trace.go:171","msg":"trace[990553040] linearizableReadLoop","detail":"{readStateIndex:29783; appliedIndex:29782; }","duration":"104.98892ms","start":"2026-04-28T23:13:39.448863Z","end":"2026-04-28T23:13:39.553852Z","steps":["trace[990553040] 'read index received' (duration: 25.096502ms)","trace[990553040] 'applied index is now lower than readState.Index' (duration: 79.891308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:39.553987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.111233ms","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-04-28T23:13:39.554046Z","caller":"traceutil/trace.go:171","msg":"trace[1888421648] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:28485; }","duration":"105.202835ms","start":"2026-04-28T23:13:39.448803Z","end":"2026-04-28T23:13:39.554006Z","steps":["trace[1888421648] 'agreement among raft nodes before linearized reading' (duration: 105.127514ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:39.554083Z","caller":"traceutil/trace.go:171","msg":"trace[134498043] transaction","detail":"{read_only:false; response_revision:28485; number_of_response:1; }","duration":"154.87119ms","start":"2026-04-28T23:13:39.399183Z","end":"2026-04-28T23:13:39.554055Z","steps":["trace[134498043] 'process raft request' (duration: 74.833278ms)","trace[134498043] 'compare' (duration: 79.321107ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:40.143137Z","caller":"traceutil/trace.go:171","msg":"trace[1097622285] transaction","detail":"{read_only:false; response_revision:28487; number_of_response:1; }","duration":"135.95401ms","start":"2026-04-28T23:13:40.007147Z","end":"2026-04-28T23:13:40.143101Z","steps":["trace[1097622285] 'process raft request' (duration: 60.494071ms)","trace[1097622285] 'compare' (duration: 75.069272ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:40.14338Z","caller":"traceutil/trace.go:171","msg":"trace[1486155361] transaction","detail":"{read_only:false; response_revision:28488; number_of_response:1; }","duration":"116.326618ms","start":"2026-04-28T23:13:40.02701Z","end":"2026-04-28T23:13:40.143337Z","steps":["trace[1486155361] 'process raft request' (duration: 116.060393ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:40.144309Z","caller":"traceutil/trace.go:171","msg":"trace[841949862] transaction","detail":"{read_only:false; response_revision:28490; number_of_response:1; }","duration":"116.093573ms","start":"2026-04-28T23:13:40.028197Z","end":"2026-04-28T23:13:40.144291Z","steps":["trace[841949862] 'process raft request' (duration: 115.96716ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:40.144296Z","caller":"traceutil/trace.go:171","msg":"trace[1227066943] transaction","detail":"{read_only:false; response_revision:28489; number_of_response:1; }","duration":"116.199674ms","start":"2026-04-28T23:13:40.028059Z","end":"2026-04-28T23:13:40.144259Z","steps":["trace[1227066943] 'process raft request' (duration: 115.877568ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:40.788328Z","caller":"traceutil/trace.go:171","msg":"trace[288418342] transaction","detail":"{read_only:false; response_revision:28492; number_of_response:1; }","duration":"290.636046ms","start":"2026-04-28T23:13:40.497666Z","end":"2026-04-28T23:13:40.788302Z","steps":["trace[288418342] 'process raft request' (duration: 290.442782ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:40.884646Z","caller":"traceutil/trace.go:171","msg":"trace[495160081] linearizableReadLoop","detail":"{readStateIndex:29792; appliedIndex:29789; }","duration":"364.155217ms","start":"2026-04-28T23:13:40.520467Z","end":"2026-04-28T23:13:40.884622Z","steps":["trace[495160081] 'read index received' (duration: 267.645955ms)","trace[495160081] 'applied index is now lower than readState.Index' (duration: 96.508462ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:40.884777Z","caller":"traceutil/trace.go:171","msg":"trace[1518300534] transaction","detail":"{read_only:false; response_revision:28495; number_of_response:1; }","duration":"180.090404ms","start":"2026-04-28T23:13:40.704675Z","end":"2026-04-28T23:13:40.884765Z","steps":["trace[1518300534] 'process raft request' (duration: 179.86588ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:40.884818Z","caller":"traceutil/trace.go:171","msg":"trace[869551788] transaction","detail":"{read_only:false; response_revision:28494; number_of_response:1; }","duration":"386.053085ms","start":"2026-04-28T23:13:40.498721Z","end":"2026-04-28T23:13:40.884774Z","steps":["trace[869551788] 'process raft request' (duration: 385.774698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:40.884865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.371971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:13:40.884867Z","caller":"traceutil/trace.go:171","msg":"trace[1247794901] transaction","detail":"{read_only:false; response_revision:28496; number_of_response:1; }","duration":"135.422871ms","start":"2026-04-28T23:13:40.749413Z","end":"2026-04-28T23:13:40.884836Z","steps":["trace[1247794901] 'process raft request' (duration: 135.168596ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:40.884923Z","caller":"traceutil/trace.go:171","msg":"trace[1971603350] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:28496; }","duration":"364.474393ms","start":"2026-04-28T23:13:40.520433Z","end":"2026-04-28T23:13:40.884908Z","steps":["trace[1971603350] 'agreement among raft nodes before linearized reading' (duration: 364.361341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:40.884962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:40.520419Z","time spent":"364.532024ms","remote":"127.0.0.1:46470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"info","ts":"2026-04-28T23:13:40.884966Z","caller":"traceutil/trace.go:171","msg":"trace[228810946] transaction","detail":"{read_only:false; response_revision:28493; number_of_response:1; }","duration":"386.31195ms","start":"2026-04-28T23:13:40.498628Z","end":"2026-04-28T23:13:40.88494Z","steps":["trace[228810946] 'process raft request' (duration: 311.321269ms)","trace[228810946] 'compare' (duration: 74.352637ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:40.885044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.92318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-28T23:13:40.885039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:40.498714Z","time spent":"386.201848ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:13:40.885081Z","caller":"traceutil/trace.go:171","msg":"trace[1380216177] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:28496; }","duration":"165.003051ms","start":"2026-04-28T23:13:40.720065Z","end":"2026-04-28T23:13:40.885068Z","steps":["trace[1380216177] 'agreement among raft nodes before linearized reading' (duration: 164.9337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:40.885099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:40.498606Z","time spent":"386.413062ms","remote":"127.0.0.1:36386","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-04-28T23:13:41.114542Z","caller":"traceutil/trace.go:171","msg":"trace[1231049656] transaction","detail":"{read_only:false; response_revision:28497; number_of_response:1; }","duration":"199.007362ms","start":"2026-04-28T23:13:40.915517Z","end":"2026-04-28T23:13:41.114524Z","steps":["trace[1231049656] 'process raft request' (duration: 125.840087ms)","trace[1231049656] 'compare' (duration: 73.055483ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:41.115068Z","caller":"traceutil/trace.go:171","msg":"trace[838684793] transaction","detail":"{read_only:false; response_revision:28498; number_of_response:1; }","duration":"194.743787ms","start":"2026-04-28T23:13:40.920302Z","end":"2026-04-28T23:13:41.115046Z","steps":["trace[838684793] 'process raft request' (duration: 194.197796ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:41.7654Z","caller":"traceutil/trace.go:171","msg":"trace[2091920777] linearizableReadLoop","detail":"{readStateIndex:29800; appliedIndex:29799; }","duration":"127.787427ms","start":"2026-04-28T23:13:41.637584Z","end":"2026-04-28T23:13:41.765372Z","steps":["trace[2091920777] 'read index received' (duration: 127.598523ms)","trace[2091920777] 'applied index is now lower than readState.Index' (duration: 187.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:41.765724Z","caller":"traceutil/trace.go:171","msg":"trace[928318493] transaction","detail":"{read_only:false; response_revision:28502; number_of_response:1; }","duration":"132.9597ms","start":"2026-04-28T23:13:41.632746Z","end":"2026-04-28T23:13:41.765706Z","steps":["trace[928318493] 'process raft request' (duration: 132.49893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:41.766472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.866768ms","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-04-28T23:13:41.766521Z","caller":"traceutil/trace.go:171","msg":"trace[1592625491] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28502; }","duration":"128.95197ms","start":"2026-04-28T23:13:41.637556Z","end":"2026-04-28T23:13:41.766508Z","steps":["trace[1592625491] 'agreement among raft nodes before linearized reading' (duration: 127.92928ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:42.233276Z","caller":"traceutil/trace.go:171","msg":"trace[670643899] linearizableReadLoop","detail":"{readStateIndex:29803; appliedIndex:29802; }","duration":"191.039502ms","start":"2026-04-28T23:13:42.042206Z","end":"2026-04-28T23:13:42.233246Z","steps":["trace[670643899] 'read index received' (duration: 190.780687ms)","trace[670643899] 'applied index is now lower than readState.Index' (duration: 257.285µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:42.233409Z","caller":"traceutil/trace.go:171","msg":"trace[583811159] transaction","detail":"{read_only:false; response_revision:28505; number_of_response:1; }","duration":"244.350979ms","start":"2026-04-28T23:13:41.989028Z","end":"2026-04-28T23:13:42.233379Z","steps":["trace[583811159] 'process raft request' (duration: 243.960711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:42.233857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.630544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-28T23:13:42.233925Z","caller":"traceutil/trace.go:171","msg":"trace[700381396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28505; }","duration":"191.758676ms","start":"2026-04-28T23:13:42.042149Z","end":"2026-04-28T23:13:42.233907Z","steps":["trace[700381396] 'agreement among raft nodes before linearized reading' (duration: 191.226626ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:42.402396Z","caller":"traceutil/trace.go:171","msg":"trace[1863185126] transaction","detail":"{read_only:false; response_revision:28506; number_of_response:1; }","duration":"254.179426ms","start":"2026-04-28T23:13:42.14819Z","end":"2026-04-28T23:13:42.402369Z","steps":["trace[1863185126] 'process raft request' (duration: 237.529173ms)","trace[1863185126] 'compare' (duration: 16.451529ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:42.402672Z","caller":"traceutil/trace.go:171","msg":"trace[1251815889] transaction","detail":"{read_only:false; response_revision:28507; number_of_response:1; }","duration":"253.547012ms","start":"2026-04-28T23:13:42.149107Z","end":"2026-04-28T23:13:42.402654Z","steps":["trace[1251815889] 'process raft request' (duration: 253.217126ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:42.402895Z","caller":"traceutil/trace.go:171","msg":"trace[1793794350] transaction","detail":"{read_only:false; response_revision:28508; number_of_response:1; }","duration":"253.525834ms","start":"2026-04-28T23:13:42.149361Z","end":"2026-04-28T23:13:42.402886Z","steps":["trace[1793794350] 'process raft request' (duration: 253.250588ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:42.403347Z","caller":"traceutil/trace.go:171","msg":"trace[911507226] linearizableReadLoop","detail":"{readStateIndex:29807; appliedIndex:29803; }","duration":"169.975222ms","start":"2026-04-28T23:13:42.233353Z","end":"2026-04-28T23:13:42.403328Z","steps":["trace[911507226] 'read index received' (duration: 152.341248ms)","trace[911507226] 'applied index is now lower than readState.Index' (duration: 17.632554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:42.403492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.030213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-28T23:13:42.403542Z","caller":"traceutil/trace.go:171","msg":"trace[983352551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28509; }","duration":"296.131156ms","start":"2026-04-28T23:13:42.107399Z","end":"2026-04-28T23:13:42.40353Z","steps":["trace[983352551] 'agreement among raft nodes before linearized reading' (duration: 296.007243ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:42.403424Z","caller":"traceutil/trace.go:171","msg":"trace[1683879889] transaction","detail":"{read_only:false; response_revision:28509; number_of_response:1; }","duration":"254.013773ms","start":"2026-04-28T23:13:42.14938Z","end":"2026-04-28T23:13:42.403394Z","steps":["trace[1683879889] 'process raft request' (duration: 253.465831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:42.621979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.291767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-28T23:13:42.622352Z","caller":"traceutil/trace.go:171","msg":"trace[248928059] transaction","detail":"{read_only:false; response_revision:28510; number_of_response:1; }","duration":"382.961724ms","start":"2026-04-28T23:13:42.23936Z","end":"2026-04-28T23:13:42.622321Z","steps":["trace[248928059] 'process raft request' (duration: 280.187977ms)","trace[248928059] 'compare' (duration: 102.059743ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:42.622538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:42.239336Z","time spent":"383.075565ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T23:13:42.622818Z","caller":"traceutil/trace.go:171","msg":"trace[1437155179] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28510; }","duration":"381.329671ms","start":"2026-04-28T23:13:42.241455Z","end":"2026-04-28T23:13:42.622784Z","steps":["trace[1437155179] 'process raft request' (duration: 380.701838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:42.622912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T23:13:42.241444Z","time spent":"381.425422ms","remote":"127.0.0.1:36592","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T23:13:42.623038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.163705ms","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-04-28T23:13:42.623101Z","caller":"traceutil/trace.go:171","msg":"trace[1337286118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28511; }","duration":"235.263708ms","start":"2026-04-28T23:13:42.387828Z","end":"2026-04-28T23:13:42.623092Z","steps":["trace[1337286118] 'agreement among raft nodes before linearized reading' (duration: 235.032683ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:42.622842Z","caller":"traceutil/trace.go:171","msg":"trace[1290286870] linearizableReadLoop","detail":"{readStateIndex:29809; appliedIndex:29807; }","duration":"219.413881ms","start":"2026-04-28T23:13:42.403414Z","end":"2026-04-28T23:13:42.622828Z","steps":["trace[1290286870] 'read index received' (duration: 116.066123ms)","trace[1290286870] 'applied index is now lower than readState.Index' (duration: 103.345528ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:42.623419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.963041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-28T23:13:42.623457Z","caller":"traceutil/trace.go:171","msg":"trace[39193813] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:28511; }","duration":"155.042262ms","start":"2026-04-28T23:13:42.468403Z","end":"2026-04-28T23:13:42.623446Z","steps":["trace[39193813] 'agreement among raft nodes before linearized reading' (duration: 154.924579ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:42.923295Z","caller":"traceutil/trace.go:171","msg":"trace[934793302] linearizableReadLoop","detail":"{readStateIndex:29812; appliedIndex:29811; }","duration":"130.230035ms","start":"2026-04-28T23:13:42.793047Z","end":"2026-04-28T23:13:42.923278Z","steps":["trace[934793302] 'read index received' (duration: 130.204595ms)","trace[934793302] 'applied index is now lower than readState.Index' (duration: 24.68µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:42.923372Z","caller":"traceutil/trace.go:171","msg":"trace[443056132] transaction","detail":"{read_only:false; response_revision:28513; number_of_response:1; }","duration":"291.974122ms","start":"2026-04-28T23:13:42.631373Z","end":"2026-04-28T23:13:42.923347Z","steps":["trace[443056132] 'process raft request' (duration: 291.755457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:42.923577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.503491ms","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-04-28T23:13:42.923657Z","caller":"traceutil/trace.go:171","msg":"trace[1797201444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28513; }","duration":"130.636313ms","start":"2026-04-28T23:13:42.793003Z","end":"2026-04-28T23:13:42.923639Z","steps":["trace[1797201444] 'agreement among raft nodes before linearized reading' (duration: 130.383839ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:43.150074Z","caller":"traceutil/trace.go:171","msg":"trace[657009796] transaction","detail":"{read_only:false; response_revision:28514; number_of_response:1; }","duration":"284.533623ms","start":"2026-04-28T23:13:42.865508Z","end":"2026-04-28T23:13:43.150042Z","steps":["trace[657009796] 'process raft request' (duration: 246.326029ms)","trace[657009796] 'compare' (duration: 38.077591ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:43.15018Z","caller":"traceutil/trace.go:171","msg":"trace[826377698] linearizableReadLoop","detail":"{readStateIndex:29813; appliedIndex:29812; }","duration":"226.802808ms","start":"2026-04-28T23:13:42.923346Z","end":"2026-04-28T23:13:43.150149Z","steps":["trace[826377698] 'read index received' (duration: 188.617464ms)","trace[826377698] 'applied index is now lower than readState.Index' (duration: 38.182974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T23:13:43.150462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.713036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-28T23:13:43.15051Z","caller":"traceutil/trace.go:171","msg":"trace[1701915192] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28516; }","duration":"259.803608ms","start":"2026-04-28T23:13:42.89069Z","end":"2026-04-28T23:13:43.150494Z","steps":["trace[1701915192] 'agreement among raft nodes before linearized reading' (duration: 259.608434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:43.150518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.169465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11495"} {"level":"info","ts":"2026-04-28T23:13:43.15056Z","caller":"traceutil/trace.go:171","msg":"trace[2125186019] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28516; }","duration":"196.258177ms","start":"2026-04-28T23:13:42.954289Z","end":"2026-04-28T23:13:43.150547Z","steps":["trace[2125186019] 'agreement among raft nodes before linearized reading' (duration: 196.074193ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:43.150636Z","caller":"traceutil/trace.go:171","msg":"trace[665014499] transaction","detail":"{read_only:false; response_revision:28515; number_of_response:1; }","duration":"220.501271ms","start":"2026-04-28T23:13:42.93011Z","end":"2026-04-28T23:13:43.150612Z","steps":["trace[665014499] 'process raft request' (duration: 219.93795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:43.150761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.065633ms","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-04-28T23:13:43.150839Z","caller":"traceutil/trace.go:171","msg":"trace[1599056094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28516; }","duration":"262.185406ms","start":"2026-04-28T23:13:42.888635Z","end":"2026-04-28T23:13:43.150821Z","steps":["trace[1599056094] 'agreement among raft nodes before linearized reading' (duration: 261.997592ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:43.289716Z","caller":"traceutil/trace.go:171","msg":"trace[1165071360] linearizableReadLoop","detail":"{readStateIndex:29817; appliedIndex:29815; }","duration":"127.687985ms","start":"2026-04-28T23:13:43.162004Z","end":"2026-04-28T23:13:43.289692Z","steps":["trace[1165071360] 'read index received' (duration: 58.707085ms)","trace[1165071360] 'applied index is now lower than readState.Index' (duration: 68.97985ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:43.290027Z","caller":"traceutil/trace.go:171","msg":"trace[284537087] transaction","detail":"{read_only:false; response_revision:28518; number_of_response:1; }","duration":"134.084743ms","start":"2026-04-28T23:13:43.155915Z","end":"2026-04-28T23:13:43.289999Z","steps":["trace[284537087] 'process raft request' (duration: 123.639844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:43.290172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.153114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-04-28T23:13:43.290224Z","caller":"traceutil/trace.go:171","msg":"trace[297939367] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:28518; }","duration":"128.246766ms","start":"2026-04-28T23:13:43.161961Z","end":"2026-04-28T23:13:43.290208Z","steps":["trace[297939367] 'agreement among raft nodes before linearized reading' (duration: 128.003622ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:43.537558Z","caller":"traceutil/trace.go:171","msg":"trace[1319734931] transaction","detail":"{read_only:false; response_revision:28519; number_of_response:1; }","duration":"243.753798ms","start":"2026-04-28T23:13:43.293769Z","end":"2026-04-28T23:13:43.537523Z","steps":["trace[1319734931] 'process raft request' (duration: 179.760337ms)","trace[1319734931] 'compare' (duration: 63.542171ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:43.537587Z","caller":"traceutil/trace.go:171","msg":"trace[222179005] linearizableReadLoop","detail":"{readStateIndex:29820; appliedIndex:29818; }","duration":"138.530273ms","start":"2026-04-28T23:13:43.399033Z","end":"2026-04-28T23:13:43.537563Z","steps":["trace[222179005] 'read index received' (duration: 74.550932ms)","trace[222179005] 'applied index is now lower than readState.Index' (duration: 63.978361ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:13:43.537635Z","caller":"traceutil/trace.go:171","msg":"trace[648811067] transaction","detail":"{read_only:false; response_revision:28520; number_of_response:1; }","duration":"179.206926ms","start":"2026-04-28T23:13:43.358398Z","end":"2026-04-28T23:13:43.537605Z","steps":["trace[648811067] 'process raft request' (duration: 179.062493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T23:13:43.537879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.827639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-28T23:13:43.537925Z","caller":"traceutil/trace.go:171","msg":"trace[1061113198] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:28520; }","duration":"138.92334ms","start":"2026-04-28T23:13:43.398989Z","end":"2026-04-28T23:13:43.537912Z","steps":["trace[1061113198] 'agreement among raft nodes before linearized reading' (duration: 138.699485ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T23:13:43.732687Z","caller":"traceutil/trace.go:171","msg":"trace[1005810536] transaction","detail":"{read_only:false; response_revision:28522; number_of_response:1; }","duration":"138.423249ms","start":"2026-04-28T23:13:43.594233Z","end":"2026-04-28T23:13:43.732657Z","steps":["trace[1005810536] 'process raft request' (duration: 81.00988ms)","trace[1005810536] 'compare' (duration: 57.278336ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:14:02.822028Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T23:14:02.826768Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-28T23:14:02.82694Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-28T23:14:09.42562Z","caller":"traceutil/trace.go:171","msg":"trace[1666049880] transaction","detail":"{read_only:false; response_revision:28894; number_of_response:1; }","duration":"106.862108ms","start":"2026-04-28T23:14:09.318727Z","end":"2026-04-28T23:14:09.425589Z","steps":["trace[1666049880] 'process raft request' (duration: 88.309126ms)","trace[1666049880] 'compare' (duration: 18.293156ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T23:17:10.083287Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27475} {"level":"info","ts":"2026-04-28T23:17:10.232274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27475,"took":"144.790047ms","hash":2274812681,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":38453248,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-04-28T23:17:10.232331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2274812681,"revision":27475,"compact-revision":23810} {"level":"info","ts":"2026-04-28T23:22:10.089735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31007} {"level":"info","ts":"2026-04-28T23:22:10.242958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31007,"took":"148.877159ms","hash":3102146139,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":36065280,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-28T23:22:10.243036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3102146139,"revision":31007,"compact-revision":27475} {"level":"info","ts":"2026-04-28T23:27:10.095983Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33704} {"level":"info","ts":"2026-04-28T23:27:10.226696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33704,"took":"126.260363ms","hash":3495617887,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":34422784,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-28T23:27:10.226744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3495617887,"revision":33704,"compact-revision":31007} {"level":"info","ts":"2026-04-28T23:30:30.030107Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-28T23:30:30.035427Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-28T23:30:30.035533Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-28T23:32:10.101959Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36386} {"level":"info","ts":"2026-04-28T23:32:10.232865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36386,"took":"127.198132ms","hash":1400520028,"current-db-size-bytes":61886464,"current-db-size":"62 MB","current-db-size-in-use-bytes":34938880,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-28T23:32:10.232915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1400520028,"revision":36386,"compact-revision":33704} {"level":"info","ts":"2026-04-28T23:37:10.109275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39159} {"level":"info","ts":"2026-04-28T23:37:10.259308Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39159,"took":"145.305624ms","hash":825541455,"current-db-size-bytes":62926848,"current-db-size":"63 MB","current-db-size-in-use-bytes":34959360,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-28T23:37:10.259384Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":825541455,"revision":39159,"compact-revision":36386}