{"level":"warn","ts":"2026-03-13T23:05:18.732244Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:05:18.732331Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-03-13T23:05:18.732394Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:05:18.732405Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-03-13T23:05:18.732422Z","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-03-13T23:05:18.733059Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-03-13T23:05:18.733142Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-03-13T23:05:18.736672Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.343332ms"} {"level":"info","ts":"2026-03-13T23:05:18.748703Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-03-13T23:05:18.748796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T23:05:18.748822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-03-13T23:05:18.74884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T23:05:18.748852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-03-13T23:05:18.748901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-03-13T23:05:18.753895Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T23:05:18.755408Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T23:05:18.756669Z","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-03-13T23:05:18.759539Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T23:05:18.759716Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T23:05:18.759761Z","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-03-13T23:05:18.760474Z","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-03-13T23:05:18.760492Z","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-03-13T23:05:18.761391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-03-13T23:05:18.761497Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-03-13T23:05:18.765281Z","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-03-13T23:05:18.76538Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-03-13T23:05:18.765431Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-03-13T23:05:18.765844Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T23:05:18.765894Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T23:05:18.849358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T23:05:18.849452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T23:05:18.849478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-03-13T23:05:18.849669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-03-13T23:05:18.849696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-03-13T23:05:18.849713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-03-13T23:05:18.849877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-03-13T23:05:18.850987Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:05:18.851864Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T23:05:18.852043Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:05:18.852086Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:05:18.852257Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T23:05:18.852286Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T23:05:18.852396Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:05:18.852594Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:05:18.852634Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:05:18.855274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-03-13T23:05:18.855466Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T23:05:31.457835Z","caller":"traceutil/trace.go:171","msg":"trace[1945276230] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"231.685447ms","start":"2026-03-13T23:05:31.226124Z","end":"2026-03-13T23:05:31.45781Z","steps":["trace[1945276230] 'process raft request' (duration: 231.533523ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:31.834335Z","caller":"traceutil/trace.go:171","msg":"trace[941582651] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"133.57321ms","start":"2026-03-13T23:05:31.700739Z","end":"2026-03-13T23:05:31.834312Z","steps":["trace[941582651] 'process raft request' (duration: 133.161089ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:57.306492Z","caller":"traceutil/trace.go:171","msg":"trace[892844782] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"132.828844ms","start":"2026-03-13T23:05:57.173632Z","end":"2026-03-13T23:05:57.306461Z","steps":["trace[892844782] 'read index received' (duration: 132.600767ms)","trace[892844782] 'applied index is now lower than readState.Index' (duration: 227.007µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:05:57.306631Z","caller":"traceutil/trace.go:171","msg":"trace[678715843] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"153.007032ms","start":"2026-03-13T23:05:57.153582Z","end":"2026-03-13T23:05:57.306589Z","steps":["trace[678715843] 'process raft request' (duration: 152.726785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:57.306762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.109942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-13T23:05:57.306851Z","caller":"traceutil/trace.go:171","msg":"trace[449474770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"133.285886ms","start":"2026-03-13T23:05:57.173552Z","end":"2026-03-13T23:05:57.306838Z","steps":["trace[449474770] 'agreement among raft nodes before linearized reading' (duration: 133.028549ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:58.017828Z","caller":"traceutil/trace.go:171","msg":"trace[878194465] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"230.088595ms","start":"2026-03-13T23:05:57.787706Z","end":"2026-03-13T23:05:58.017794Z","steps":["trace[878194465] 'process raft request' (duration: 229.689253ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:05:58.214475Z","caller":"traceutil/trace.go:171","msg":"trace[16401413] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:573; }","duration":"208.171926ms","start":"2026-03-13T23:05:58.006263Z","end":"2026-03-13T23:05:58.214434Z","steps":["trace[16401413] 'read index received' (duration: 11.070052ms)","trace[16401413] 'applied index is now lower than readState.Index' (duration: 197.099324ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:05:58.214761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.197462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:05:58.214819Z","caller":"traceutil/trace.go:171","msg":"trace[877975403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"145.343736ms","start":"2026-03-13T23:05:58.069461Z","end":"2026-03-13T23:05:58.214805Z","steps":["trace[877975403] 'agreement among raft nodes before linearized reading' (duration: 145.168661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:05:58.214779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.487515ms","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-03-13T23:05:58.214891Z","caller":"traceutil/trace.go:171","msg":"trace[638215377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"208.655139ms","start":"2026-03-13T23:05:58.0062Z","end":"2026-03-13T23:05:58.214855Z","steps":["trace[638215377] 'agreement among raft nodes before linearized reading' (duration: 208.383102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:13.169297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.099016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-439537b8-39e2-436b-b2c0-18326362f233\" ","response":"range_response_count:1 size:3625"} {"level":"info","ts":"2026-03-13T23:07:13.169487Z","caller":"traceutil/trace.go:171","msg":"trace[889680441] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-439537b8-39e2-436b-b2c0-18326362f233; range_end:; response_count:1; response_revision:1601; }","duration":"302.356983ms","start":"2026-03-13T23:07:12.867104Z","end":"2026-03-13T23:07:13.169461Z","steps":["trace[889680441] 'range keys from in-memory index tree' (duration: 301.653604ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:13.169661Z","caller":"traceutil/trace.go:171","msg":"trace[1582803301] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"301.613962ms","start":"2026-03-13T23:07:12.868019Z","end":"2026-03-13T23:07:13.169633Z","steps":["trace[1582803301] 'process raft request' (duration: 268.719499ms)","trace[1582803301] 'compare' (duration: 32.343628ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:13.169897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:12.867077Z","time spent":"302.802896ms","remote":"127.0.0.1:56524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-439537b8-39e2-436b-b2c0-18326362f233\" "} {"level":"warn","ts":"2026-03-13T23:07:13.170276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:12.867989Z","time spent":"301.723564ms","remote":"127.0.0.1:56602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:07:26.047403Z","caller":"traceutil/trace.go:171","msg":"trace[1262905173] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"100.466493ms","start":"2026-03-13T23:07:25.946877Z","end":"2026-03-13T23:07:26.047344Z","steps":["trace[1262905173] 'process raft request' (duration: 89.489364ms)","trace[1262905173] 'compare' (duration: 10.525506ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:08:03.034148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.927789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:08:03.034292Z","caller":"traceutil/trace.go:171","msg":"trace[2067377274] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"154.88505ms","start":"2026-03-13T23:08:02.879388Z","end":"2026-03-13T23:08:03.034273Z","steps":["trace[2067377274] 'read index received' (duration: 8.477388ms)","trace[2067377274] 'applied index is now lower than readState.Index' (duration: 146.405812ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:08:03.034363Z","caller":"traceutil/trace.go:171","msg":"trace[1040550467] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"273.650906ms","start":"2026-03-13T23:08:02.760664Z","end":"2026-03-13T23:08:03.034315Z","steps":["trace[1040550467] 'process raft request' (duration: 127.315376ms)","trace[1040550467] 'compare' (duration: 145.645141ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:08:03.034552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.676091ms","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-03-13T23:08:03.034595Z","caller":"traceutil/trace.go:171","msg":"trace[492686951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1901; }","duration":"119.752862ms","start":"2026-03-13T23:08:02.914831Z","end":"2026-03-13T23:08:03.034584Z","steps":["trace[492686951] 'agreement among raft nodes before linearized reading' (duration: 119.552727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:03.034554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.160128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-03-13T23:08:03.034672Z","caller":"traceutil/trace.go:171","msg":"trace[867212903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1901; }","duration":"155.275962ms","start":"2026-03-13T23:08:02.879381Z","end":"2026-03-13T23:08:03.034657Z","steps":["trace[867212903] 'agreement among raft nodes before linearized reading' (duration: 154.964803ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:08:03.191426Z","caller":"traceutil/trace.go:171","msg":"trace[1206278588] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"151.687001ms","start":"2026-03-13T23:08:03.039698Z","end":"2026-03-13T23:08:03.191385Z","steps":["trace[1206278588] 'read index received' (duration: 149.679995ms)","trace[1206278588] 'applied index is now lower than readState.Index' (duration: 2.005766ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:08:03.191489Z","caller":"traceutil/trace.go:171","msg":"trace[1822954253] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"152.888594ms","start":"2026-03-13T23:08:03.038561Z","end":"2026-03-13T23:08:03.19145Z","steps":["trace[1822954253] 'process raft request' (duration: 150.872598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:03.19173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.994721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-13T23:08:03.191824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.383972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-13T23:08:03.191851Z","caller":"traceutil/trace.go:171","msg":"trace[1793197408] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1902; }","duration":"152.146865ms","start":"2026-03-13T23:08:03.039675Z","end":"2026-03-13T23:08:03.191822Z","steps":["trace[1793197408] 'agreement among raft nodes before linearized reading' (duration: 151.877488ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:08:03.191867Z","caller":"traceutil/trace.go:171","msg":"trace[299636911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1902; }","duration":"141.466474ms","start":"2026-03-13T23:08:03.050387Z","end":"2026-03-13T23:08:03.191854Z","steps":["trace[299636911] 'agreement among raft nodes before linearized reading' (duration: 141.205307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:03.192059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.384466ms","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-03-13T23:08:03.192185Z","caller":"traceutil/trace.go:171","msg":"trace[1845968549] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1902; }","duration":"139.560451ms","start":"2026-03-13T23:08:03.052605Z","end":"2026-03-13T23:08:03.192165Z","steps":["trace[1845968549] 'agreement among raft nodes before linearized reading' (duration: 139.130179ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:09:16.362805Z","caller":"traceutil/trace.go:171","msg":"trace[1408608587] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"121.301924ms","start":"2026-03-13T23:09:16.241473Z","end":"2026-03-13T23:09:16.362775Z","steps":["trace[1408608587] 'process raft request' (duration: 121.096388ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:09:21.813068Z","caller":"traceutil/trace.go:171","msg":"trace[428095846] linearizableReadLoop","detail":"{readStateIndex:2586; appliedIndex:2585; }","duration":"129.952539ms","start":"2026-03-13T23:09:21.683075Z","end":"2026-03-13T23:09:21.813027Z","steps":["trace[428095846] 'read index received' (duration: 119.556845ms)","trace[428095846] 'applied index is now lower than readState.Index' (duration: 10.394294ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:09:21.813116Z","caller":"traceutil/trace.go:171","msg":"trace[1378136794] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"157.323834ms","start":"2026-03-13T23:09:21.655732Z","end":"2026-03-13T23:09:21.813056Z","steps":["trace[1378136794] 'process raft request' (duration: 146.862698ms)","trace[1378136794] 'compare' (duration: 10.295911ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:09:21.813364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.091481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:09:21.813334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.48772ms","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-03-13T23:09:21.813437Z","caller":"traceutil/trace.go:171","msg":"trace[1136320097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2485; }","duration":"173.216844ms","start":"2026-03-13T23:09:21.640201Z","end":"2026-03-13T23:09:21.813418Z","steps":["trace[1136320097] 'agreement among raft nodes before linearized reading' (duration: 172.970397ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:09:21.813475Z","caller":"traceutil/trace.go:171","msg":"trace[538508185] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2485; }","duration":"168.661475ms","start":"2026-03-13T23:09:21.644774Z","end":"2026-03-13T23:09:21.813436Z","steps":["trace[538508185] 'agreement among raft nodes before linearized reading' (duration: 168.323385ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:09:21.826875Z","caller":"traceutil/trace.go:171","msg":"trace[812344033] transaction","detail":"{read_only:false; response_revision:2486; number_of_response:1; }","duration":"138.646786ms","start":"2026-03-13T23:09:21.6882Z","end":"2026-03-13T23:09:21.826847Z","steps":["trace[812344033] 'process raft request' (duration: 138.492911ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:08.771893Z","caller":"traceutil/trace.go:171","msg":"trace[177870866] transaction","detail":"{read_only:false; response_revision:4188; number_of_response:1; }","duration":"147.822216ms","start":"2026-03-13T23:13:08.624036Z","end":"2026-03-13T23:13:08.771858Z","steps":["trace[177870866] 'process raft request' (duration: 112.11218ms)","trace[177870866] 'compare' (duration: 35.164011ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:08.772164Z","caller":"traceutil/trace.go:171","msg":"trace[1453332735] linearizableReadLoop","detail":"{readStateIndex:4360; appliedIndex:4359; }","duration":"121.660485ms","start":"2026-03-13T23:13:08.650471Z","end":"2026-03-13T23:13:08.772132Z","steps":["trace[1453332735] 'read index received' (duration: 85.61942ms)","trace[1453332735] 'applied index is now lower than readState.Index' (duration: 36.039755ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:13:08.77256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.080908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:3238"} {"level":"info","ts":"2026-03-13T23:13:08.772603Z","caller":"traceutil/trace.go:171","msg":"trace[1287439740] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4189; }","duration":"122.201972ms","start":"2026-03-13T23:13:08.650386Z","end":"2026-03-13T23:13:08.772588Z","steps":["trace[1287439740] 'agreement among raft nodes before linearized reading' (duration: 122.037006ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:16.313056Z","caller":"traceutil/trace.go:171","msg":"trace[1092275381] transaction","detail":"{read_only:false; response_revision:4379; number_of_response:1; }","duration":"127.912399ms","start":"2026-03-13T23:13:16.185093Z","end":"2026-03-13T23:13:16.313006Z","steps":["trace[1092275381] 'process raft request' (duration: 65.978181ms)","trace[1092275381] 'compare' (duration: 61.562198ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:16.317215Z","caller":"traceutil/trace.go:171","msg":"trace[1136161111] linearizableReadLoop","detail":"{readStateIndex:4556; appliedIndex:4554; }","duration":"130.494793ms","start":"2026-03-13T23:13:16.186671Z","end":"2026-03-13T23:13:16.317166Z","steps":["trace[1136161111] 'read index received' (duration: 64.533362ms)","trace[1136161111] 'applied index is now lower than readState.Index' (duration: 65.960631ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:16.317315Z","caller":"traceutil/trace.go:171","msg":"trace[1502193537] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4379; }","duration":"131.782628ms","start":"2026-03-13T23:13:16.185493Z","end":"2026-03-13T23:13:16.317275Z","steps":["trace[1502193537] 'process raft request' (duration: 131.580732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:16.317482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.77434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-03-13T23:13:16.317522Z","caller":"traceutil/trace.go:171","msg":"trace[770755387] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4379; }","duration":"130.899524ms","start":"2026-03-13T23:13:16.186611Z","end":"2026-03-13T23:13:16.317511Z","steps":["trace[770755387] 'agreement among raft nodes before linearized reading' (duration: 130.673598ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:21.465848Z","caller":"traceutil/trace.go:171","msg":"trace[1880654896] linearizableReadLoop","detail":"{readStateIndex:4746; appliedIndex:4745; }","duration":"118.56941ms","start":"2026-03-13T23:13:21.347248Z","end":"2026-03-13T23:13:21.465817Z","steps":["trace[1880654896] 'read index received' (duration: 118.298673ms)","trace[1880654896] 'applied index is now lower than readState.Index' (duration: 269.717µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:13:21.465975Z","caller":"traceutil/trace.go:171","msg":"trace[1154814790] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"253.521037ms","start":"2026-03-13T23:13:21.212408Z","end":"2026-03-13T23:13:21.465929Z","steps":["trace[1154814790] 'process raft request' (duration: 253.214709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:21.466116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.833047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5560459-68b2-4f7d-a9c7-0388fa660527\" ","response":"range_response_count:1 size:3716"} {"level":"info","ts":"2026-03-13T23:13:21.466251Z","caller":"traceutil/trace.go:171","msg":"trace[147986910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d5560459-68b2-4f7d-a9c7-0388fa660527; range_end:; response_count:1; response_revision:4565; }","duration":"119.020812ms","start":"2026-03-13T23:13:21.347204Z","end":"2026-03-13T23:13:21.466225Z","steps":["trace[147986910] 'agreement among raft nodes before linearized reading' (duration: 118.739915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:39.946347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.953769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a3c7cfb6-6e89-4e91-a2e7-12c2ba29e240\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-03-13T23:13:39.946453Z","caller":"traceutil/trace.go:171","msg":"trace[1740783074] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a3c7cfb6-6e89-4e91-a2e7-12c2ba29e240; range_end:; response_count:1; response_revision:4923; }","duration":"141.124574ms","start":"2026-03-13T23:13:39.805307Z","end":"2026-03-13T23:13:39.946431Z","steps":["trace[1740783074] 'range keys from in-memory index tree' (duration: 140.760432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:39.946552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.953536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:13:39.946818Z","caller":"traceutil/trace.go:171","msg":"trace[1871998854] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"122.75528ms","start":"2026-03-13T23:13:39.824027Z","end":"2026-03-13T23:13:39.946782Z","steps":["trace[1871998854] 'process raft request' (duration: 19.461344ms)","trace[1871998854] 'compare' (duration: 102.817273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:13:40.161214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.374435ms","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:5"} {"level":"info","ts":"2026-03-13T23:13:40.161336Z","caller":"traceutil/trace.go:171","msg":"trace[2146767623] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:4925; }","duration":"100.57896ms","start":"2026-03-13T23:13:40.060727Z","end":"2026-03-13T23:13:40.161306Z","steps":["trace[2146767623] 'count revisions from in-memory index tree' (duration: 100.269341ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:13:53.153823Z","caller":"traceutil/trace.go:171","msg":"trace[990350501] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"161.168992ms","start":"2026-03-13T23:13:52.99262Z","end":"2026-03-13T23:13:53.153789Z","steps":["trace[990350501] 'process raft request' (duration: 160.859033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:53.91389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.656262ms","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-03-13T23:13:53.913985Z","caller":"traceutil/trace.go:171","msg":"trace[1220883220] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5107; }","duration":"100.777345ms","start":"2026-03-13T23:13:53.813185Z","end":"2026-03-13T23:13:53.913963Z","steps":["trace[1220883220] 'range keys from in-memory index tree' (duration: 100.444265ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:13:53.913992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.830595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.189c8993401d71b3\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-03-13T23:13:53.91408Z","caller":"traceutil/trace.go:171","msg":"trace[2091748037] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.189c8993401d71b3; range_end:; response_count:1; response_revision:5107; }","duration":"101.992261ms","start":"2026-03-13T23:13:53.812067Z","end":"2026-03-13T23:13:53.914059Z","steps":["trace[2091748037] 'range keys from in-memory index tree' (duration: 101.589878ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:15:19.533123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2769} {"level":"info","ts":"2026-03-13T23:15:19.585869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2769,"took":"51.434283ms","hash":3666594186,"current-db-size-bytes":41644032,"current-db-size":"42 MB","current-db-size-in-use-bytes":31322112,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-13T23:15:19.585955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3666594186,"revision":2769,"compact-revision":-1} {"level":"info","ts":"2026-03-13T23:19:42.166869Z","caller":"traceutil/trace.go:171","msg":"trace[1347470325] linearizableReadLoop","detail":"{readStateIndex:7848; appliedIndex:7847; }","duration":"108.05547ms","start":"2026-03-13T23:19:42.058789Z","end":"2026-03-13T23:19:42.166845Z","steps":["trace[1347470325] 'read index received' (duration: 107.863864ms)","trace[1347470325] 'applied index is now lower than readState.Index' (duration: 190.256µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:19:42.166973Z","caller":"traceutil/trace.go:171","msg":"trace[2118942338] transaction","detail":"{read_only:false; response_revision:7569; number_of_response:1; }","duration":"313.425957ms","start":"2026-03-13T23:19:41.853494Z","end":"2026-03-13T23:19:42.16692Z","steps":["trace[2118942338] 'process raft request' (duration: 313.18322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:19:42.16715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.296686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T23:19:42.167212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:19:41.853463Z","time spent":"313.627022ms","remote":"127.0.0.1:56602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:19:42.167267Z","caller":"traceutil/trace.go:171","msg":"trace[1370919484] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7569; }","duration":"108.490361ms","start":"2026-03-13T23:19:42.058749Z","end":"2026-03-13T23:19:42.16724Z","steps":["trace[1370919484] 'agreement among raft nodes before linearized reading' (duration: 108.261135ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:19:42.360347Z","caller":"traceutil/trace.go:171","msg":"trace[300633334] transaction","detail":"{read_only:false; response_revision:7571; number_of_response:1; }","duration":"125.98533ms","start":"2026-03-13T23:19:42.234325Z","end":"2026-03-13T23:19:42.36031Z","steps":["trace[300633334] 'process raft request' (duration: 119.407836ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:19.539781Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5770} {"level":"info","ts":"2026-03-13T23:20:19.632675Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5770,"took":"90.203486ms","hash":1415445602,"current-db-size-bytes":43589632,"current-db-size":"44 MB","current-db-size-in-use-bytes":23334912,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-13T23:20:19.632738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1415445602,"revision":5770,"compact-revision":2769} {"level":"info","ts":"2026-03-13T23:20:23.50748Z","caller":"traceutil/trace.go:171","msg":"trace[1493518235] transaction","detail":"{read_only:false; response_revision:7826; number_of_response:1; }","duration":"185.234647ms","start":"2026-03-13T23:20:23.322209Z","end":"2026-03-13T23:20:23.507443Z","steps":["trace[1493518235] 'process raft request' (duration: 184.596209ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:36.400136Z","caller":"traceutil/trace.go:171","msg":"trace[1234517626] transaction","detail":"{read_only:false; response_revision:7932; number_of_response:1; }","duration":"247.77364ms","start":"2026-03-13T23:20:36.152335Z","end":"2026-03-13T23:20:36.400109Z","steps":["trace[1234517626] 'process raft request' (duration: 247.609345ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:36.916063Z","caller":"traceutil/trace.go:171","msg":"trace[553963151] transaction","detail":"{read_only:false; response_revision:7936; number_of_response:1; }","duration":"122.95562ms","start":"2026-03-13T23:20:36.793071Z","end":"2026-03-13T23:20:36.916026Z","steps":["trace[553963151] 'process raft request' (duration: 110.504062ms)","trace[553963151] 'compare' (duration: 11.904032ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:37.244079Z","caller":"traceutil/trace.go:171","msg":"trace[1865650287] linearizableReadLoop","detail":"{readStateIndex:8234; appliedIndex:8233; }","duration":"175.378802ms","start":"2026-03-13T23:20:37.06866Z","end":"2026-03-13T23:20:37.244039Z","steps":["trace[1865650287] 'read index received' (duration: 175.033932ms)","trace[1865650287] 'applied index is now lower than readState.Index' (duration: 343.11µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:37.244193Z","caller":"traceutil/trace.go:171","msg":"trace[1861365428] transaction","detail":"{read_only:false; response_revision:7937; number_of_response:1; }","duration":"182.607134ms","start":"2026-03-13T23:20:37.061543Z","end":"2026-03-13T23:20:37.24415Z","steps":["trace[1861365428] 'process raft request' (duration: 182.215382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:37.244329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.639849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:20:37.244379Z","caller":"traceutil/trace.go:171","msg":"trace[452905370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7937; }","duration":"175.785803ms","start":"2026-03-13T23:20:37.068582Z","end":"2026-03-13T23:20:37.244367Z","steps":["trace[452905370] 'agreement among raft nodes before linearized reading' (duration: 175.66999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:20:37.244539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.490507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-13T23:20:37.244637Z","caller":"traceutil/trace.go:171","msg":"trace[2084929805] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7937; }","duration":"155.659362ms","start":"2026-03-13T23:20:37.088959Z","end":"2026-03-13T23:20:37.244618Z","steps":["trace[2084929805] 'agreement among raft nodes before linearized reading' (duration: 155.380264ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:38.179707Z","caller":"traceutil/trace.go:171","msg":"trace[1883775798] transaction","detail":"{read_only:false; response_revision:7946; number_of_response:1; }","duration":"119.571585ms","start":"2026-03-13T23:20:38.060104Z","end":"2026-03-13T23:20:38.179676Z","steps":["trace[1883775798] 'process raft request' (duration: 119.432721ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:20:38.179785Z","caller":"traceutil/trace.go:171","msg":"trace[1054173689] linearizableReadLoop","detail":"{readStateIndex:8243; appliedIndex:8242; }","duration":"119.922754ms","start":"2026-03-13T23:20:38.059842Z","end":"2026-03-13T23:20:38.179765Z","steps":["trace[1054173689] 'read index received' (duration: 30.986353ms)","trace[1054173689] 'applied index is now lower than readState.Index' (duration: 88.934861ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:20:38.179888Z","caller":"traceutil/trace.go:171","msg":"trace[229985232] transaction","detail":"{read_only:false; response_revision:7945; number_of_response:1; }","duration":"141.846136ms","start":"2026-03-13T23:20:38.03803Z","end":"2026-03-13T23:20:38.179877Z","steps":["trace[229985232] 'process raft request' (duration: 52.750871ms)","trace[229985232] 'compare' (duration: 88.374305ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:20:38.180453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.603044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-wmvbt\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-03-13T23:20:38.180544Z","caller":"traceutil/trace.go:171","msg":"trace[942447358] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-wmvbt; range_end:; response_count:1; response_revision:7946; }","duration":"120.747287ms","start":"2026-03-13T23:20:38.059775Z","end":"2026-03-13T23:20:38.180522Z","steps":["trace[942447358] 'agreement among raft nodes before linearized reading' (duration: 120.275524ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:21:52.091327Z","caller":"traceutil/trace.go:171","msg":"trace[207922940] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"125.997074ms","start":"2026-03-13T23:21:51.965265Z","end":"2026-03-13T23:21:52.091262Z","steps":["trace[207922940] 'process raft request' (duration: 125.764206ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:24:13.898746Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-13T23:24:13.905513Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-13T23:24:13.905668Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-13T23:25:19.547522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7800} {"level":"info","ts":"2026-03-13T23:25:19.612888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7800,"took":"62.465249ms","hash":2400020782,"current-db-size-bytes":43589632,"current-db-size":"44 MB","current-db-size-in-use-bytes":26304512,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-13T23:25:19.612951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2400020782,"revision":7800,"compact-revision":5770} {"level":"info","ts":"2026-03-13T23:30:19.553237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10003} {"level":"info","ts":"2026-03-13T23:30:19.632441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10003,"took":"77.36541ms","hash":3993398473,"current-db-size-bytes":43589632,"current-db-size":"44 MB","current-db-size-in-use-bytes":16916480,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-13T23:30:19.632495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3993398473,"revision":10003,"compact-revision":7800}