{"level":"warn","ts":"2026-03-24T14:52:28.773512Z","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-24T14:52:28.773605Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.247:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.247:2380","--initial-cluster=instance=https://199.19.213.247:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.247:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.247: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-24T14:52:28.773679Z","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-24T14:52:28.773692Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.247:2380"]}
{"level":"info","ts":"2026-03-24T14:52:28.77371Z","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-24T14:52:28.77434Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.247:2379"]}
{"level":"info","ts":"2026-03-24T14:52:28.774424Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.247:2380"],"listen-peer-urls":["https://199.19.213.247:2380"],"advertise-client-urls":["https://199.19.213.247:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.247:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.247: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-24T14:52:28.779372Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.676242ms"}
{"level":"info","ts":"2026-03-24T14:52:28.794589Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8ec24054f9d92918","cluster-id":"f45e237da85474b5"}
{"level":"info","ts":"2026-03-24T14:52:28.794757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-24T14:52:28.794796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became follower at term 0"}
{"level":"info","ts":"2026-03-24T14:52:28.794814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8ec24054f9d92918 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-24T14:52:28.794825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became follower at term 1"}
{"level":"info","ts":"2026-03-24T14:52:28.79488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 switched to configuration voters=(10286855232580823320)"}
{"level":"warn","ts":"2026-03-24T14:52:28.807742Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-24T14:52:28.809541Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-24T14:52:28.81248Z","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-24T14:52:28.816855Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8ec24054f9d92918","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-24T14:52:28.81736Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8ec24054f9d92918","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-24T14:52:28.817466Z","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-24T14:52:28.817494Z","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-24T14:52:28.817501Z","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-24T14:52:28.82123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 switched to configuration voters=(10286855232580823320)"}
{"level":"info","ts":"2026-03-24T14:52:28.821418Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f45e237da85474b5","local-member-id":"8ec24054f9d92918","added-peer-id":"8ec24054f9d92918","added-peer-peer-urls":["https://199.19.213.247:2380"]}
{"level":"info","ts":"2026-03-24T14:52:28.82173Z","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-24T14:52:28.821952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8ec24054f9d92918","initial-advertise-peer-urls":["https://199.19.213.247:2380"],"listen-peer-urls":["https://199.19.213.247:2380"],"advertise-client-urls":["https://199.19.213.247:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.247:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-24T14:52:28.821987Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-24T14:52:28.822077Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.247:2380"}
{"level":"info","ts":"2026-03-24T14:52:28.822173Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.247:2380"}
{"level":"info","ts":"2026-03-24T14:52:28.896016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-24T14:52:28.896091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-24T14:52:28.896128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 received MsgPreVoteResp from 8ec24054f9d92918 at term 1"}
{"level":"info","ts":"2026-03-24T14:52:28.896149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became candidate at term 2"}
{"level":"info","ts":"2026-03-24T14:52:28.896162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 received MsgVoteResp from 8ec24054f9d92918 at term 2"}
{"level":"info","ts":"2026-03-24T14:52:28.896182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8ec24054f9d92918 became leader at term 2"}
{"level":"info","ts":"2026-03-24T14:52:28.896198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8ec24054f9d92918 elected leader 8ec24054f9d92918 at term 2"}
{"level":"info","ts":"2026-03-24T14:52:28.897168Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8ec24054f9d92918","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.247:2379]}","request-path":"/0/members/8ec24054f9d92918/attributes","cluster-id":"f45e237da85474b5","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-24T14:52:28.897182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-24T14:52:28.89733Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-24T14:52:28.897342Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-24T14:52:28.897661Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-24T14:52:28.897833Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-24T14:52:28.898573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f45e237da85474b5","local-member-id":"8ec24054f9d92918","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-24T14:52:28.898787Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-24T14:52:28.898876Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-24T14:52:28.90058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.247:2379"}
{"level":"info","ts":"2026-03-24T14:52:28.90102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-24T14:53:06.344818Z","caller":"traceutil/trace.go:171","msg":"trace[552964256] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"205.981256ms","start":"2026-03-24T14:53:06.138807Z","end":"2026-03-24T14:53:06.344788Z","steps":["trace[552964256] 'process raft request'  (duration: 205.76434ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:53:07.077961Z","caller":"traceutil/trace.go:171","msg":"trace[826821894] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"167.765459ms","start":"2026-03-24T14:53:06.910159Z","end":"2026-03-24T14:53:07.077925Z","steps":["trace[826821894] 'read index received'  (duration: 167.397009ms)","trace[826821894] 'applied index is now lower than readState.Index'  (duration: 366.6µs)"],"step_count":2}
{"level":"info","ts":"2026-03-24T14:53:07.078004Z","caller":"traceutil/trace.go:171","msg":"trace[804266245] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"171.117833ms","start":"2026-03-24T14:53:06.906851Z","end":"2026-03-24T14:53:07.077969Z","steps":["trace[804266245] 'process raft request'  (duration: 170.856616ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T14:53:07.078217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.009586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-24T14:53:07.078349Z","caller":"traceutil/trace.go:171","msg":"trace[679284102] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:560; }","duration":"168.197751ms","start":"2026-03-24T14:53:06.910127Z","end":"2026-03-24T14:53:07.078325Z","steps":["trace[679284102] 'agreement among raft nodes before linearized reading'  (duration: 167.971225ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:53:08.486595Z","caller":"traceutil/trace.go:171","msg":"trace[1302963780] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"109.654753ms","start":"2026-03-24T14:53:08.376907Z","end":"2026-03-24T14:53:08.486562Z","steps":["trace[1302963780] 'process raft request'  (duration: 107.643427ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:54:27.096308Z","caller":"traceutil/trace.go:171","msg":"trace[1649465397] linearizableReadLoop","detail":"{readStateIndex:1756; appliedIndex:1755; }","duration":"147.276997ms","start":"2026-03-24T14:54:26.949Z","end":"2026-03-24T14:54:27.096277Z","steps":["trace[1649465397] 'read index received'  (duration: 147.075572ms)","trace[1649465397] 'applied index is now lower than readState.Index'  (duration: 200.205µs)"],"step_count":2}
{"level":"info","ts":"2026-03-24T14:54:27.09656Z","caller":"traceutil/trace.go:171","msg":"trace[2012143179] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"148.841629ms","start":"2026-03-24T14:54:26.947488Z","end":"2026-03-24T14:54:27.09633Z","steps":["trace[2012143179] 'process raft request'  (duration: 148.580752ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T14:54:27.096711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.690498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-626mv\" ","response":"range_response_count:1 size:5821"}
{"level":"info","ts":"2026-03-24T14:54:27.096775Z","caller":"traceutil/trace.go:171","msg":"trace[311756797] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-626mv; range_end:; response_count:1; response_revision:1691; }","duration":"147.76918ms","start":"2026-03-24T14:54:26.948989Z","end":"2026-03-24T14:54:27.096758Z","steps":["trace[311756797] 'agreement among raft nodes before linearized reading'  (duration: 147.556385ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:55:00.684096Z","caller":"traceutil/trace.go:171","msg":"trace[1448036031] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"185.429538ms","start":"2026-03-24T14:55:00.49863Z","end":"2026-03-24T14:55:00.684059Z","steps":["trace[1448036031] 'read index received'  (duration: 100.34786ms)","trace[1448036031] 'applied index is now lower than readState.Index'  (duration: 85.080188ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-24T14:55:00.684585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.944402ms","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-03-24T14:55:00.684622Z","caller":"traceutil/trace.go:171","msg":"trace[209376863] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"216.204087ms","start":"2026-03-24T14:55:00.468348Z","end":"2026-03-24T14:55:00.684552Z","steps":["trace[209376863] 'process raft request'  (duration: 130.601784ms)","trace[209376863] 'compare'  (duration: 84.919763ms)"],"step_count":2}
{"level":"info","ts":"2026-03-24T14:55:00.684669Z","caller":"traceutil/trace.go:171","msg":"trace[781303345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1865; }","duration":"186.039485ms","start":"2026-03-24T14:55:00.498612Z","end":"2026-03-24T14:55:00.684651Z","steps":["trace[781303345] 'agreement among raft nodes before linearized reading'  (duration: 185.818339ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:55:00.766374Z","caller":"traceutil/trace.go:171","msg":"trace[1390992736] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"150.312279ms","start":"2026-03-24T14:55:00.616021Z","end":"2026-03-24T14:55:00.766334Z","steps":["trace[1390992736] 'process raft request'  (duration: 150.080552ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T14:55:00.766518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.958143ms","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-24T14:55:00.766604Z","caller":"traceutil/trace.go:171","msg":"trace[961323355] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1866; }","duration":"169.079835ms","start":"2026-03-24T14:55:00.597499Z","end":"2026-03-24T14:55:00.766579Z","steps":["trace[961323355] 'agreement among raft nodes before linearized reading'  (duration: 168.738786ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T14:55:00.986525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.615696ms","expected-duration":"100ms","prefix":"","request":"header:<ID:2961289517190285858 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1856 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-24T14:55:00.986805Z","caller":"traceutil/trace.go:171","msg":"trace[1827279890] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"298.242002ms","start":"2026-03-24T14:55:00.688524Z","end":"2026-03-24T14:55:00.986766Z","steps":["trace[1827279890] 'process raft request'  (duration: 125.886304ms)","trace[1827279890] 'compare'  (duration: 171.498313ms)"],"step_count":2}
{"level":"info","ts":"2026-03-24T14:55:00.98706Z","caller":"traceutil/trace.go:171","msg":"trace[280243663] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1939; }","duration":"149.528597ms","start":"2026-03-24T14:55:00.837502Z","end":"2026-03-24T14:55:00.98703Z","steps":["trace[280243663] 'read index received'  (duration: 132.652621ms)","trace[280243663] 'applied index is now lower than readState.Index'  (duration: 16.874906ms)"],"step_count":2}
{"level":"info","ts":"2026-03-24T14:55:00.987201Z","caller":"traceutil/trace.go:171","msg":"trace[418412646] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"216.249758ms","start":"2026-03-24T14:55:00.77092Z","end":"2026-03-24T14:55:00.98717Z","steps":["trace[418412646] 'process raft request'  (duration: 215.720033ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T14:55:00.987438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.937339ms","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-24T14:55:00.987478Z","caller":"traceutil/trace.go:171","msg":"trace[1890439017] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1868; }","duration":"150.016101ms","start":"2026-03-24T14:55:00.837451Z","end":"2026-03-24T14:55:00.987467Z","steps":["trace[1890439017] 'agreement among raft nodes before linearized reading'  (duration: 149.649041ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T14:56:15.927672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.784904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-24T14:56:15.927691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.19263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"}
{"level":"info","ts":"2026-03-24T14:56:15.927766Z","caller":"traceutil/trace.go:171","msg":"trace[767166339] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:2433; }","duration":"165.931398ms","start":"2026-03-24T14:56:15.761813Z","end":"2026-03-24T14:56:15.927744Z","steps":["trace[767166339] 'count revisions from in-memory index tree'  (duration: 165.703082ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:56:15.927778Z","caller":"traceutil/trace.go:171","msg":"trace[1917110448] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2433; }","duration":"150.321693ms","start":"2026-03-24T14:56:15.777434Z","end":"2026-03-24T14:56:15.927755Z","steps":["trace[1917110448] 'range keys from in-memory index tree'  (duration: 150.031175ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:56:26.917589Z","caller":"traceutil/trace.go:171","msg":"trace[1084636974] linearizableReadLoop","detail":"{readStateIndex:2584; appliedIndex:2583; }","duration":"100.4916ms","start":"2026-03-24T14:56:26.817079Z","end":"2026-03-24T14:56:26.91757Z","steps":["trace[1084636974] 'read index received'  (duration: 100.248403ms)","trace[1084636974] 'applied index is now lower than readState.Index'  (duration: 242.277µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-24T14:56:26.917774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.673995ms","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-03-24T14:56:26.917801Z","caller":"traceutil/trace.go:171","msg":"trace[1400987711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2485; }","duration":"100.742247ms","start":"2026-03-24T14:56:26.817052Z","end":"2026-03-24T14:56:26.917794Z","steps":["trace[1400987711] 'agreement among raft nodes before linearized reading'  (duration: 100.630364ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:56:26.917855Z","caller":"traceutil/trace.go:171","msg":"trace[1947918585] transaction","detail":"{read_only:false; response_revision:2485; number_of_response:1; }","duration":"101.90088ms","start":"2026-03-24T14:56:26.815927Z","end":"2026-03-24T14:56:26.917828Z","steps":["trace[1947918585] 'process raft request'  (duration: 101.494608ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T14:58:46.271284Z","caller":"traceutil/trace.go:171","msg":"trace[947188951] transaction","detail":"{read_only:false; response_revision:3350; number_of_response:1; }","duration":"127.006197ms","start":"2026-03-24T14:58:46.144254Z","end":"2026-03-24T14:58:46.27126Z","steps":["trace[947188951] 'process raft request'  (duration: 126.860663ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:00:19.162074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.254349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-24T15:00:19.162196Z","caller":"traceutil/trace.go:171","msg":"trace[1615795525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4630; }","duration":"164.430935ms","start":"2026-03-24T15:00:18.997742Z","end":"2026-03-24T15:00:19.162173Z","steps":["trace[1615795525] 'range keys from in-memory index tree'  (duration: 164.140085ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:00:19.636497Z","caller":"traceutil/trace.go:171","msg":"trace[1950507637] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"190.501669ms","start":"2026-03-24T15:00:19.445944Z","end":"2026-03-24T15:00:19.636445Z","steps":["trace[1950507637] 'process raft request'  (duration: 140.782399ms)","trace[1950507637] 'compare'  (duration: 49.499663ms)"],"step_count":2}
{"level":"info","ts":"2026-03-24T15:00:19.835801Z","caller":"traceutil/trace.go:171","msg":"trace[1254339578] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"389.805274ms","start":"2026-03-24T15:00:19.445973Z","end":"2026-03-24T15:00:19.835779Z","steps":["trace[1254339578] 'process raft request'  (duration: 303.491998ms)","trace[1254339578] 'compare'  (duration: 86.172621ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-24T15:00:19.835927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:00:19.445951Z","time spent":"389.909307ms","remote":"127.0.0.1:38196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/endpointslices/monitoring/kube-prometheus-stack-alertmanager-469vs\" mod_revision:3916 > success:<request_put:<key:\"/registry/endpointslices/monitoring/kube-prometheus-stack-alertmanager-469vs\" value_size:1784 >> failure:<request_range:<key:\"/registry/endpointslices/monitoring/kube-prometheus-stack-alertmanager-469vs\" > >"}
{"level":"info","ts":"2026-03-24T15:00:19.836102Z","caller":"traceutil/trace.go:171","msg":"trace[1531041643] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"389.05106ms","start":"2026-03-24T15:00:19.447016Z","end":"2026-03-24T15:00:19.836067Z","steps":["trace[1531041643] 'process raft request'  (duration: 388.722209ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:00:19.836115Z","caller":"traceutil/trace.go:171","msg":"trace[1439613756] linearizableReadLoop","detail":"{readStateIndex:4819; appliedIndex:4817; }","duration":"389.384681ms","start":"2026-03-24T15:00:19.446705Z","end":"2026-03-24T15:00:19.83609Z","steps":["trace[1439613756] 'read index received'  (duration: 140.209781ms)","trace[1439613756] 'applied index is now lower than readState.Index'  (duration: 249.1739ms)"],"step_count":2}
{"level":"info","ts":"2026-03-24T15:00:19.836138Z","caller":"traceutil/trace.go:171","msg":"trace[1713404869] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"387.451127ms","start":"2026-03-24T15:00:19.448654Z","end":"2026-03-24T15:00:19.836105Z","steps":["trace[1713404869] 'process raft request'  (duration: 387.331583ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:00:19.836218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:00:19.446998Z","time spent":"389.171123ms","remote":"127.0.0.1:38106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1282,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/monitoring/kube-prometheus-stack-alertmanager\" mod_revision:3915 > success:<request_put:<key:\"/registry/services/endpoints/monitoring/kube-prometheus-stack-alertmanager\" value_size:1200 >> failure:<request_range:<key:\"/registry/services/endpoints/monitoring/kube-prometheus-stack-alertmanager\" > >"}
{"level":"warn","ts":"2026-03-24T15:00:19.836281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:00:19.448641Z","time spent":"387.559301ms","remote":"127.0.0.1:38106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/monitoring/alertmanager-operated\" mod_revision:4185 > success:<request_put:<key:\"/registry/services/endpoints/monitoring/alertmanager-operated\" value_size:836 >> failure:<request_range:<key:\"/registry/services/endpoints/monitoring/alertmanager-operated\" > >"}
{"level":"warn","ts":"2026-03-24T15:00:19.836302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.643068ms","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:1935"}
{"level":"info","ts":"2026-03-24T15:00:19.836331Z","caller":"traceutil/trace.go:171","msg":"trace[1551340977] 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:4638; }","duration":"389.672279ms","start":"2026-03-24T15:00:19.446649Z","end":"2026-03-24T15:00:19.836322Z","steps":["trace[1551340977] 'agreement among raft nodes before linearized reading'  (duration: 389.558555ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:00:19.836353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:00:19.446617Z","time spent":"389.731931ms","remote":"127.0.0.1:38092","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":"warn","ts":"2026-03-24T15:00:19.836366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.903365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"}
{"level":"info","ts":"2026-03-24T15:00:19.836418Z","caller":"traceutil/trace.go:171","msg":"trace[663122611] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4638; }","duration":"388.998508ms","start":"2026-03-24T15:00:19.447404Z","end":"2026-03-24T15:00:19.836403Z","steps":["trace[663122611] 'agreement among raft nodes before linearized reading'  (duration: 388.828232ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:00:19.836459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:00:19.447394Z","time spent":"389.05541ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16257,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "}
{"level":"warn","ts":"2026-03-24T15:00:19.836552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.507031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-24T15:00:19.836646Z","caller":"traceutil/trace.go:171","msg":"trace[1483060250] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:4638; }","duration":"107.671007ms","start":"2026-03-24T15:00:19.728953Z","end":"2026-03-24T15:00:19.836625Z","steps":["trace[1483060250] 'agreement among raft nodes before linearized reading'  (duration: 107.481921ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:00:20.011718Z","caller":"traceutil/trace.go:171","msg":"trace[1631318075] transaction","detail":"{read_only:false; response_revision:4643; number_of_response:1; }","duration":"114.164377ms","start":"2026-03-24T15:00:19.897512Z","end":"2026-03-24T15:00:20.011677Z","steps":["trace[1631318075] 'process raft request'  (duration: 112.514904ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:00:20.011735Z","caller":"traceutil/trace.go:171","msg":"trace[1942928764] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"113.846077ms","start":"2026-03-24T15:00:19.89786Z","end":"2026-03-24T15:00:20.011706Z","steps":["trace[1942928764] 'process raft request'  (duration: 113.733254ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:00:20.011963Z","caller":"traceutil/trace.go:171","msg":"trace[487284739] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"113.687412ms","start":"2026-03-24T15:00:19.898254Z","end":"2026-03-24T15:00:20.011941Z","steps":["trace[487284739] 'process raft request'  (duration: 113.398843ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:00:30.416176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.011199ms","expected-duration":"100ms","prefix":"","request":"header:<ID:2961289517190293178 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:4748 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:476 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-24T15:00:30.416483Z","caller":"traceutil/trace.go:171","msg":"trace[1395347876] linearizableReadLoop","detail":"{readStateIndex:5009; appliedIndex:5008; }","duration":"275.362118ms","start":"2026-03-24T15:00:30.14109Z","end":"2026-03-24T15:00:30.416452Z","steps":["trace[1395347876] 'read index received'  (duration: 15.821683ms)","trace[1395347876] 'applied index is now lower than readState.Index'  (duration: 259.538995ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-24T15:00:30.416723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.641208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-03-24T15:00:30.416764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.482874ms","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-03-24T15:00:30.416784Z","caller":"traceutil/trace.go:171","msg":"trace[898381381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4822; }","duration":"275.74646ms","start":"2026-03-24T15:00:30.141012Z","end":"2026-03-24T15:00:30.416759Z","steps":["trace[898381381] 'agreement among raft nodes before linearized reading'  (duration: 275.576945ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:00:30.416818Z","caller":"traceutil/trace.go:171","msg":"trace[916137309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4822; }","duration":"272.578347ms","start":"2026-03-24T15:00:30.144227Z","end":"2026-03-24T15:00:30.416806Z","steps":["trace[916137309] 'agreement among raft nodes before linearized reading'  (duration: 272.390251ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:00:30.416846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.495938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-10624fc5-8118-4da7-8dac-c02e74661ccf\" ","response":"range_response_count:1 size:3766"}
{"level":"info","ts":"2026-03-24T15:00:30.416897Z","caller":"traceutil/trace.go:171","msg":"trace[1905199518] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-10624fc5-8118-4da7-8dac-c02e74661ccf; range_end:; response_count:1; response_revision:4822; }","duration":"189.58468ms","start":"2026-03-24T15:00:30.227301Z","end":"2026-03-24T15:00:30.416885Z","steps":["trace[1905199518] 'agreement among raft nodes before linearized reading'  (duration: 189.474697ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:00:30.417338Z","caller":"traceutil/trace.go:171","msg":"trace[185510491] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"371.348257ms","start":"2026-03-24T15:00:30.04509Z","end":"2026-03-24T15:00:30.416438Z","steps":["trace[185510491] 'process raft request'  (duration: 111.930495ms)","trace[185510491] 'compare'  (duration: 258.785021ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-24T15:00:30.417539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:00:30.045061Z","time spent":"372.347728ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:4748 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:476 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-03-24T15:02:29.191652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2789}
{"level":"info","ts":"2026-03-24T15:02:29.26146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2789,"took":"68.363103ms","hash":1650728495,"current-db-size-bytes":41181184,"current-db-size":"41 MB","current-db-size-in-use-bytes":30973952,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-03-24T15:02:29.261561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1650728495,"revision":2789,"compact-revision":-1}
{"level":"info","ts":"2026-03-24T15:07:03.905783Z","caller":"traceutil/trace.go:171","msg":"trace[88702461] transaction","detail":"{read_only:false; response_revision:7666; number_of_response:1; }","duration":"334.104375ms","start":"2026-03-24T15:07:03.571657Z","end":"2026-03-24T15:07:03.905761Z","steps":["trace[88702461] 'process raft request'  (duration: 333.945601ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:07:03.905928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:07:03.571636Z","time spent":"334.223869ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:7656 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-03-24T15:07:14.9297Z","caller":"traceutil/trace.go:171","msg":"trace[741411730] transaction","detail":"{read_only:false; response_revision:7718; number_of_response:1; }","duration":"131.925224ms","start":"2026-03-24T15:07:14.797742Z","end":"2026-03-24T15:07:14.929668Z","steps":["trace[741411730] 'process raft request'  (duration: 131.651526ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:07:15.665311Z","caller":"traceutil/trace.go:171","msg":"trace[789362501] transaction","detail":"{read_only:false; response_revision:7722; number_of_response:1; }","duration":"181.574364ms","start":"2026-03-24T15:07:15.483711Z","end":"2026-03-24T15:07:15.665285Z","steps":["trace[789362501] 'process raft request'  (duration: 181.40619ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:07:29.197958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5734}
{"level":"info","ts":"2026-03-24T15:07:29.28463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5734,"took":"84.714382ms","hash":2423133401,"current-db-size-bytes":42778624,"current-db-size":"43 MB","current-db-size-in-use-bytes":23429120,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-03-24T15:07:29.284704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2423133401,"revision":5734,"compact-revision":2789}
{"level":"info","ts":"2026-03-24T15:07:41.487793Z","caller":"traceutil/trace.go:171","msg":"trace[525100131] transaction","detail":"{read_only:false; response_revision:7900; number_of_response:1; }","duration":"257.606434ms","start":"2026-03-24T15:07:41.230166Z","end":"2026-03-24T15:07:41.487773Z","steps":["trace[525100131] 'process raft request'  (duration: 257.47798ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:07:41.69587Z","caller":"traceutil/trace.go:171","msg":"trace[477070590] linearizableReadLoop","detail":"{readStateIndex:8197; appliedIndex:8196; }","duration":"277.136801ms","start":"2026-03-24T15:07:41.418706Z","end":"2026-03-24T15:07:41.695843Z","steps":["trace[477070590] 'read index received'  (duration: 69.582469ms)","trace[477070590] 'applied index is now lower than readState.Index'  (duration: 207.553092ms)"],"step_count":2}
{"level":"info","ts":"2026-03-24T15:07:41.696067Z","caller":"traceutil/trace.go:171","msg":"trace[2060474723] transaction","detail":"{read_only:false; response_revision:7901; number_of_response:1; }","duration":"461.00444ms","start":"2026-03-24T15:07:41.235048Z","end":"2026-03-24T15:07:41.696052Z","steps":["trace[2060474723] 'process raft request'  (duration: 458.054487ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:07:41.696251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T15:07:41.235031Z","time spent":"461.110323ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:7891 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:463 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-24T15:07:41.696365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.629994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9jfjz\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-03-24T15:07:41.696486Z","caller":"traceutil/trace.go:171","msg":"trace[1922445646] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9jfjz; range_end:; response_count:1; response_revision:7901; }","duration":"277.80599ms","start":"2026-03-24T15:07:41.418641Z","end":"2026-03-24T15:07:41.696447Z","steps":["trace[1922445646] 'agreement among raft nodes before linearized reading'  (duration: 277.646955ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:07:43.818849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.930863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9jfjz\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-03-24T15:07:43.818959Z","caller":"traceutil/trace.go:171","msg":"trace[620694565] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9jfjz; range_end:; response_count:1; response_revision:7911; }","duration":"113.089527ms","start":"2026-03-24T15:07:43.705846Z","end":"2026-03-24T15:07:43.818935Z","steps":["trace[620694565] 'range keys from in-memory index tree'  (duration: 112.83234ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:07:43.819142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.408184ms","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-24T15:07:43.819196Z","caller":"traceutil/trace.go:171","msg":"trace[840649924] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7911; }","duration":"119.502086ms","start":"2026-03-24T15:07:43.69968Z","end":"2026-03-24T15:07:43.819182Z","steps":["trace[840649924] 'range keys from in-memory index tree'  (duration: 119.24496ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:07:53.799616Z","caller":"traceutil/trace.go:171","msg":"trace[1285789050] transaction","detail":"{read_only:false; response_revision:8003; number_of_response:1; }","duration":"184.746444ms","start":"2026-03-24T15:07:53.61483Z","end":"2026-03-24T15:07:53.799577Z","steps":["trace[1285789050] 'process raft request'  (duration: 137.018567ms)","trace[1285789050] 'compare'  (duration: 47.576933ms)"],"step_count":2}
{"level":"info","ts":"2026-03-24T15:07:55.33751Z","caller":"traceutil/trace.go:171","msg":"trace[794873846] linearizableReadLoop","detail":"{readStateIndex:8318; appliedIndex:8317; }","duration":"146.939085ms","start":"2026-03-24T15:07:55.190547Z","end":"2026-03-24T15:07:55.337486Z","steps":["trace[794873846] 'read index received'  (duration: 146.666567ms)","trace[794873846] 'applied index is now lower than readState.Index'  (duration: 271.438µs)"],"step_count":2}
{"level":"info","ts":"2026-03-24T15:07:55.337567Z","caller":"traceutil/trace.go:171","msg":"trace[1231748148] transaction","detail":"{read_only:false; response_revision:8016; number_of_response:1; }","duration":"212.706226ms","start":"2026-03-24T15:07:55.124823Z","end":"2026-03-24T15:07:55.337529Z","steps":["trace[1231748148] 'process raft request'  (duration: 212.49624ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-24T15:07:55.337788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.227912ms","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-03-24T15:07:55.337843Z","caller":"traceutil/trace.go:171","msg":"trace[1562049288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8016; }","duration":"147.330445ms","start":"2026-03-24T15:07:55.190499Z","end":"2026-03-24T15:07:55.33783Z","steps":["trace[1562049288] 'agreement among raft nodes before linearized reading'  (duration: 147.1422ms)"],"step_count":1}
{"level":"info","ts":"2026-03-24T15:11:22.21856Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8ec24054f9d92918","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-24T15:11:22.228377Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-03-24T15:11:22.228489Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-03-24T15:12:29.204368Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7794}
{"level":"info","ts":"2026-03-24T15:12:29.268806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7794,"took":"62.199321ms","hash":2533150809,"current-db-size-bytes":42778624,"current-db-size":"43 MB","current-db-size-in-use-bytes":25264128,"current-db-size-in-use":"25 MB"}
{"level":"info","ts":"2026-03-24T15:12:29.268884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2533150809,"revision":7794,"compact-revision":5734}
{"level":"info","ts":"2026-03-24T15:17:29.211545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10070}
{"level":"info","ts":"2026-03-24T15:17:29.289592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10070,"took":"75.755673ms","hash":558123043,"current-db-size-bytes":42778624,"current-db-size":"43 MB","current-db-size-in-use-bytes":17285120,"current-db-size-in-use":"17 MB"}
{"level":"info","ts":"2026-03-24T15:17:29.289648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":558123043,"revision":10070,"compact-revision":7794}