{"level":"warn","ts":"2026-04-08T00:29:08.06933Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-08T00:29:08.069471Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6: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.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-04-08T00:29:08.069568Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-08T00:29:08.06958Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]}
{"level":"info","ts":"2026-04-08T00:29:08.069609Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-08T00:29:08.07041Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]}
{"level":"info","ts":"2026-04-08T00:29:08.070482Z","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":8,"max-cpu-available":8,"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.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-04-08T00:29:08.073808Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.112681ms"}
{"level":"info","ts":"2026-04-08T00:29:08.083801Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"}
{"level":"info","ts":"2026-04-08T00:29:08.083871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"}
{"level":"info","ts":"2026-04-08T00:29:08.083891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"}
{"level":"info","ts":"2026-04-08T00:29:08.083907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-08T00:29:08.083917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"}
{"level":"info","ts":"2026-04-08T00:29:08.083962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"}
{"level":"warn","ts":"2026-04-08T00:29:08.091444Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-08T00:29:08.092587Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-08T00:29:08.093531Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-04-08T00:29:08.0965Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-08T00:29:08.096705Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-08T00:29:08.096789Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-08T00:29:08.0968Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-08T00:29:08.096811Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-08T00:29:08.097392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"}
{"level":"info","ts":"2026-04-08T00:29:08.097499Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]}
{"level":"info","ts":"2026-04-08T00:29:08.103597Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-08T00:29:08.103728Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"}
{"level":"info","ts":"2026-04-08T00:29:08.103781Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"}
{"level":"info","ts":"2026-04-08T00:29:08.10394Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-08T00:29:08.104012Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-08T00:29:08.984615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"}
{"level":"info","ts":"2026-04-08T00:29:08.984706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-08T00:29:08.984752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"}
{"level":"info","ts":"2026-04-08T00:29:08.984772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"}
{"level":"info","ts":"2026-04-08T00:29:08.984785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"}
{"level":"info","ts":"2026-04-08T00:29:08.984801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"}
{"level":"info","ts":"2026-04-08T00:29:08.984814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"}
{"level":"info","ts":"2026-04-08T00:29:08.986088Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T00:29:08.986941Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-08T00:29:08.986934Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-08T00:29:08.986995Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-08T00:29:08.987402Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-08T00:29:08.987451Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-08T00:29:08.98775Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T00:29:08.988163Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T00:29:08.988201Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T00:29:08.990252Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-08T00:29:08.990553Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"}
{"level":"info","ts":"2026-04-08T00:29:11.410676Z","caller":"traceutil/trace.go:171","msg":"trace[1561846400] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"229.690459ms","start":"2026-04-08T00:29:11.180955Z","end":"2026-04-08T00:29:11.410646Z","steps":["trace[1561846400] 'read index received'  (duration: 229.520766ms)","trace[1561846400] 'applied index is now lower than readState.Index'  (duration: 169.013µs)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:29:11.410759Z","caller":"traceutil/trace.go:171","msg":"trace[1801015790] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"231.503543ms","start":"2026-04-08T00:29:11.179234Z","end":"2026-04-08T00:29:11.410737Z","steps":["trace[1801015790] 'process raft request'  (duration: 231.315809ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:29:11.410858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.885643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-08T00:29:11.4109Z","caller":"traceutil/trace.go:171","msg":"trace[1762676383] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:73; }","duration":"229.965556ms","start":"2026-04-08T00:29:11.180927Z","end":"2026-04-08T00:29:11.410892Z","steps":["trace[1762676383] 'agreement among raft nodes before linearized reading'  (duration: 229.838294ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:29:11.411513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.544119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-08T00:29:11.411589Z","caller":"traceutil/trace.go:171","msg":"trace[1247380032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"141.66095ms","start":"2026-04-08T00:29:11.269906Z","end":"2026-04-08T00:29:11.411567Z","steps":["trace[1247380032] 'agreement among raft nodes before linearized reading'  (duration: 141.547578ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:11.60718Z","caller":"traceutil/trace.go:171","msg":"trace[1468325297] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"191.257228ms","start":"2026-04-08T00:29:11.415894Z","end":"2026-04-08T00:29:11.607151Z","steps":["trace[1468325297] 'read index received'  (duration: 180.984ms)","trace[1468325297] 'applied index is now lower than readState.Index'  (duration: 10.272548ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T00:29:11.607345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.412351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"}
{"level":"info","ts":"2026-04-08T00:29:11.607405Z","caller":"traceutil/trace.go:171","msg":"trace[1787572834] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"192.282136ms","start":"2026-04-08T00:29:11.415059Z","end":"2026-04-08T00:29:11.607341Z","steps":["trace[1787572834] 'process raft request'  (duration: 181.851175ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:11.607443Z","caller":"traceutil/trace.go:171","msg":"trace[700166272] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:74; }","duration":"191.547634ms","start":"2026-04-08T00:29:11.415883Z","end":"2026-04-08T00:29:11.60743Z","steps":["trace[700166272] 'agreement among raft nodes before linearized reading'  (duration: 191.362911ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:40.618102Z","caller":"traceutil/trace.go:171","msg":"trace[766171888] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"119.448134ms","start":"2026-04-08T00:29:40.498606Z","end":"2026-04-08T00:29:40.618054Z","steps":["trace[766171888] 'process raft request'  (duration: 119.186512ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:40.730135Z","caller":"traceutil/trace.go:171","msg":"trace[1532640857] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"191.049735ms","start":"2026-04-08T00:29:40.539062Z","end":"2026-04-08T00:29:40.730112Z","steps":["trace[1532640857] 'process raft request'  (duration: 190.766832ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:40.730531Z","caller":"traceutil/trace.go:171","msg":"trace[1079257521] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"108.255146ms","start":"2026-04-08T00:29:40.622237Z","end":"2026-04-08T00:29:40.730492Z","steps":["trace[1079257521] 'process raft request'  (duration: 108.093604ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:43.378577Z","caller":"traceutil/trace.go:171","msg":"trace[2054497445] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"147.460241ms","start":"2026-04-08T00:29:43.231085Z","end":"2026-04-08T00:29:43.378545Z","steps":["trace[2054497445] 'process raft request'  (duration: 98.573984ms)","trace[2054497445] 'compare'  (duration: 48.753605ms)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:29:43.885894Z","caller":"traceutil/trace.go:171","msg":"trace[148344979] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"101.705848ms","start":"2026-04-08T00:29:43.784153Z","end":"2026-04-08T00:29:43.885859Z","steps":["trace[148344979] 'process raft request'  (duration: 95.57237ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:58.398567Z","caller":"traceutil/trace.go:171","msg":"trace[787329496] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"220.958715ms","start":"2026-04-08T00:29:58.177582Z","end":"2026-04-08T00:29:58.398541Z","steps":["trace[787329496] 'read index received'  (duration: 220.723852ms)","trace[787329496] 'applied index is now lower than readState.Index'  (duration: 233.943µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T00:29:58.398705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.112297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-08T00:29:58.398742Z","caller":"traceutil/trace.go:171","msg":"trace[847958035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"221.214307ms","start":"2026-04-08T00:29:58.177517Z","end":"2026-04-08T00:29:58.398732Z","steps":["trace[847958035] 'agreement among raft nodes before linearized reading'  (duration: 221.127946ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:29:58.398732Z","caller":"traceutil/trace.go:171","msg":"trace[1713814428] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"223.647361ms","start":"2026-04-08T00:29:58.175043Z","end":"2026-04-08T00:29:58.39869Z","steps":["trace[1713814428] 'process raft request'  (duration: 223.330108ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:31:15.167916Z","caller":"traceutil/trace.go:171","msg":"trace[1175087427] linearizableReadLoop","detail":"{readStateIndex:1862; appliedIndex:1861; }","duration":"196.434315ms","start":"2026-04-08T00:31:14.971454Z","end":"2026-04-08T00:31:15.167889Z","steps":["trace[1175087427] 'read index received'  (duration: 196.168101ms)","trace[1175087427] 'applied index is now lower than readState.Index'  (duration: 264.954µs)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:31:15.167963Z","caller":"traceutil/trace.go:171","msg":"trace[1959661009] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"238.456995ms","start":"2026-04-08T00:31:14.929456Z","end":"2026-04-08T00:31:15.167913Z","steps":["trace[1959661009] 'process raft request'  (duration: 238.284203ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:31:15.168219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.816959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-04-08T00:31:15.168276Z","caller":"traceutil/trace.go:171","msg":"trace[1153372730] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1795; }","duration":"193.91277ms","start":"2026-04-08T00:31:14.974341Z","end":"2026-04-08T00:31:15.168254Z","steps":["trace[1153372730] 'agreement among raft nodes before linearized reading'  (duration: 193.748727ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:31:15.168373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.805561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-04-08T00:31:15.168473Z","caller":"traceutil/trace.go:171","msg":"trace[556549337] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1795; }","duration":"197.009274ms","start":"2026-04-08T00:31:14.971439Z","end":"2026-04-08T00:31:15.168448Z","steps":["trace[556549337] 'agreement among raft nodes before linearized reading'  (duration: 196.622298ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:31:15.168399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.167989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-04-08T00:31:15.168612Z","caller":"traceutil/trace.go:171","msg":"trace[1040015543] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1795; }","duration":"162.381153ms","start":"2026-04-08T00:31:15.006188Z","end":"2026-04-08T00:31:15.168569Z","steps":["trace[1040015543] 'agreement among raft nodes before linearized reading'  (duration: 162.030797ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:31:27.73824Z","caller":"traceutil/trace.go:171","msg":"trace[2054098508] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"152.325918ms","start":"2026-04-08T00:31:27.585884Z","end":"2026-04-08T00:31:27.73821Z","steps":["trace[2054098508] 'process raft request'  (duration: 152.130534ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:31:49.707727Z","caller":"traceutil/trace.go:171","msg":"trace[431362085] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"165.097951ms","start":"2026-04-08T00:31:49.542587Z","end":"2026-04-08T00:31:49.707685Z","steps":["trace[431362085] 'process raft request'  (duration: 164.886468ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:31:49.941523Z","caller":"traceutil/trace.go:171","msg":"trace[549836533] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2108; }","duration":"107.231153ms","start":"2026-04-08T00:31:49.834263Z","end":"2026-04-08T00:31:49.941494Z","steps":["trace[549836533] 'read index received'  (duration: 106.511924ms)","trace[549836533] 'applied index is now lower than readState.Index'  (duration: 718.339µs)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:31:49.941562Z","caller":"traceutil/trace.go:171","msg":"trace[232944330] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"398.604503ms","start":"2026-04-08T00:31:49.542921Z","end":"2026-04-08T00:31:49.941525Z","steps":["trace[232944330] 'process raft request'  (duration: 397.808092ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:31:49.941581Z","caller":"traceutil/trace.go:171","msg":"trace[1657325864] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"398.609323ms","start":"2026-04-08T00:31:49.542936Z","end":"2026-04-08T00:31:49.941545Z","steps":["trace[1657325864] 'process raft request'  (duration: 398.483671ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:31:49.941729Z","caller":"traceutil/trace.go:171","msg":"trace[1816250649] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"398.632133ms","start":"2026-04-08T00:31:49.543065Z","end":"2026-04-08T00:31:49.941697Z","steps":["trace[1816250649] 'process raft request'  (duration: 398.39105ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:31:49.941755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.470706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-04-08T00:31:49.941887Z","caller":"traceutil/trace.go:171","msg":"trace[1041138172] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2036; }","duration":"107.662619ms","start":"2026-04-08T00:31:49.834212Z","end":"2026-04-08T00:31:49.941875Z","steps":["trace[1041138172] 'agreement among raft nodes before linearized reading'  (duration: 107.397286ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:31:49.942581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:49.542906Z","time spent":"398.738294ms","remote":"127.0.0.1:43058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2023 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-04-08T00:31:49.942916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:49.542912Z","time spent":"398.742835ms","remote":"127.0.0.1:43058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2025 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-04-08T00:31:49.943329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:49.543044Z","time spent":"398.781886ms","remote":"127.0.0.1:43058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:2026 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-04-08T00:32:43.727632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.48285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7458"}
{"level":"info","ts":"2026-04-08T00:32:43.727728Z","caller":"traceutil/trace.go:171","msg":"trace[131708048] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2431; }","duration":"142.608142ms","start":"2026-04-08T00:32:43.585104Z","end":"2026-04-08T00:32:43.727712Z","steps":["trace[131708048] 'range keys from in-memory index tree'  (duration: 142.325428ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:33:17.09525Z","caller":"traceutil/trace.go:171","msg":"trace[1498942361] linearizableReadLoop","detail":"{readStateIndex:2711; appliedIndex:2710; }","duration":"174.991363ms","start":"2026-04-08T00:33:16.920235Z","end":"2026-04-08T00:33:17.095226Z","steps":["trace[1498942361] 'read index received'  (duration: 133.311599ms)","trace[1498942361] 'applied index is now lower than readState.Index'  (duration: 41.678544ms)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:33:17.0954Z","caller":"traceutil/trace.go:171","msg":"trace[1083560378] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"211.367052ms","start":"2026-04-08T00:33:16.88402Z","end":"2026-04-08T00:33:17.095387Z","steps":["trace[1083560378] 'process raft request'  (duration: 169.484316ms)","trace[1083560378] 'compare'  (duration: 41.489462ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T00:33:17.095591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.344756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-04-08T00:33:17.095648Z","caller":"traceutil/trace.go:171","msg":"trace[1093638778] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2617; }","duration":"175.413598ms","start":"2026-04-08T00:33:16.920203Z","end":"2026-04-08T00:33:17.095616Z","steps":["trace[1093638778] 'agreement among raft nodes before linearized reading'  (duration: 175.337047ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:33:17.274986Z","caller":"traceutil/trace.go:171","msg":"trace[1905011701] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"169.827768ms","start":"2026-04-08T00:33:17.105133Z","end":"2026-04-08T00:33:17.274961Z","steps":["trace[1905011701] 'process raft request'  (duration: 160.998016ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:33:17.274949Z","caller":"traceutil/trace.go:171","msg":"trace[637639453] linearizableReadLoop","detail":"{readStateIndex:2712; appliedIndex:2711; }","duration":"168.571694ms","start":"2026-04-08T00:33:17.106357Z","end":"2026-04-08T00:33:17.274929Z","steps":["trace[637639453] 'read index received'  (duration: 159.699731ms)","trace[637639453] 'applied index is now lower than readState.Index'  (duration: 8.870833ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T00:33:17.27556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.18253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9093"}
{"level":"info","ts":"2026-04-08T00:33:17.275614Z","caller":"traceutil/trace.go:171","msg":"trace[575391045] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2618; }","duration":"169.261741ms","start":"2026-04-08T00:33:17.10634Z","end":"2026-04-08T00:33:17.275601Z","steps":["trace[575391045] 'agreement among raft nodes before linearized reading'  (duration: 168.681866ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:34:56.095667Z","caller":"traceutil/trace.go:171","msg":"trace[1470111249] transaction","detail":"{read_only:false; response_revision:3171; number_of_response:1; }","duration":"117.4826ms","start":"2026-04-08T00:34:55.978159Z","end":"2026-04-08T00:34:56.095642Z","steps":["trace[1470111249] 'process raft request'  (duration: 117.174721ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:36:46.187796Z","caller":"traceutil/trace.go:171","msg":"trace[1049661339] linearizableReadLoop","detail":"{readStateIndex:4454; appliedIndex:4453; }","duration":"136.249472ms","start":"2026-04-08T00:36:46.051513Z","end":"2026-04-08T00:36:46.187763Z","steps":["trace[1049661339] 'read index received'  (duration: 136.045728ms)","trace[1049661339] 'applied index is now lower than readState.Index'  (duration: 202.894µs)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:36:46.187956Z","caller":"traceutil/trace.go:171","msg":"trace[691812775] transaction","detail":"{read_only:false; response_revision:4293; number_of_response:1; }","duration":"145.13487ms","start":"2026-04-08T00:36:46.042752Z","end":"2026-04-08T00:36:46.187887Z","steps":["trace[691812775] 'process raft request'  (duration: 144.797303ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:36:46.188183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.6498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"}
{"level":"warn","ts":"2026-04-08T00:36:46.188237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.243813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:5 size:35495"}
{"level":"info","ts":"2026-04-08T00:36:46.188269Z","caller":"traceutil/trace.go:171","msg":"trace[1464413862] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4293; }","duration":"136.774494ms","start":"2026-04-08T00:36:46.051481Z","end":"2026-04-08T00:36:46.188255Z","steps":["trace[1464413862] 'agreement among raft nodes before linearized reading'  (duration: 136.561189ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:36:46.188313Z","caller":"traceutil/trace.go:171","msg":"trace[608835158] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:5; response_revision:4293; }","duration":"135.338426ms","start":"2026-04-08T00:36:46.052959Z","end":"2026-04-08T00:36:46.188297Z","steps":["trace[608835158] 'agreement among raft nodes before linearized reading'  (duration: 135.060309ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:37:05.566757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.076394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-04-08T00:37:05.566886Z","caller":"traceutil/trace.go:171","msg":"trace[1340258553] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4707; }","duration":"102.253798ms","start":"2026-04-08T00:37:05.464612Z","end":"2026-04-08T00:37:05.566866Z","steps":["trace[1340258553] 'range keys from in-memory index tree'  (duration: 101.909232ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T00:37:09.181144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.488043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-08T00:37:09.181247Z","caller":"traceutil/trace.go:171","msg":"trace[1874309037] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4737; }","duration":"235.628206ms","start":"2026-04-08T00:37:08.945593Z","end":"2026-04-08T00:37:09.181221Z","steps":["trace[1874309037] 'count revisions from in-memory index tree'  (duration: 235.421972ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:37:09.408819Z","caller":"traceutil/trace.go:171","msg":"trace[1400488467] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"106.971485ms","start":"2026-04-08T00:37:09.301824Z","end":"2026-04-08T00:37:09.408795Z","steps":["trace[1400488467] 'process raft request'  (duration: 106.851633ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:37:09.425941Z","caller":"traceutil/trace.go:171","msg":"trace[628292824] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"104.459758ms","start":"2026-04-08T00:37:09.321463Z","end":"2026-04-08T00:37:09.425923Z","steps":["trace[628292824] 'process raft request'  (duration: 104.368377ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:37:09.805401Z","caller":"traceutil/trace.go:171","msg":"trace[398846218] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4922; }","duration":"104.782933ms","start":"2026-04-08T00:37:09.700596Z","end":"2026-04-08T00:37:09.805379Z","steps":["trace[398846218] 'read index received'  (duration: 88.517139ms)","trace[398846218] 'applied index is now lower than readState.Index'  (duration: 16.264814ms)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:37:09.805548Z","caller":"traceutil/trace.go:171","msg":"trace[352791094] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"111.081041ms","start":"2026-04-08T00:37:09.694383Z","end":"2026-04-08T00:37:09.805464Z","steps":["trace[352791094] 'process raft request'  (duration: 94.796108ms)","trace[352791094] 'compare'  (duration: 16.10127ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T00:37:09.805771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.154849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-04-08T00:37:09.805815Z","caller":"traceutil/trace.go:171","msg":"trace[318790114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4749; }","duration":"105.233432ms","start":"2026-04-08T00:37:09.700569Z","end":"2026-04-08T00:37:09.805802Z","steps":["trace[318790114] 'agreement among raft nodes before linearized reading'  (duration: 104.895615ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:38:01.422424Z","caller":"traceutil/trace.go:171","msg":"trace[273543972] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"121.583241ms","start":"2026-04-08T00:38:01.300812Z","end":"2026-04-08T00:38:01.422395Z","steps":["trace[273543972] 'process raft request'  (duration: 121.425489ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T00:39:09.027013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891}
{"level":"info","ts":"2026-04-08T00:39:09.119714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"90.908179ms","hash":3455188755,"current-db-size-bytes":54091776,"current-db-size":"54 MB","current-db-size-in-use-bytes":29224960,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-04-08T00:39:09.119761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3455188755,"revision":2891,"compact-revision":-1}
{"level":"info","ts":"2026-04-08T00:41:09.642531Z","caller":"traceutil/trace.go:171","msg":"trace[478679536] transaction","detail":"{read_only:false; response_revision:6497; number_of_response:1; }","duration":"116.807586ms","start":"2026-04-08T00:41:09.525695Z","end":"2026-04-08T00:41:09.642502Z","steps":["trace[478679536] 'process raft request'  (duration: 33.034966ms)","trace[478679536] 'compare'  (duration: 83.655908ms)"],"step_count":2}
{"level":"info","ts":"2026-04-08T00:44:09.033501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5629}
{"level":"info","ts":"2026-04-08T00:44:09.121783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5629,"took":"86.426171ms","hash":2487898638,"current-db-size-bytes":54091776,"current-db-size":"54 MB","current-db-size-in-use-bytes":17879040,"current-db-size-in-use":"18 MB"}
{"level":"info","ts":"2026-04-08T00:44:09.121833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2487898638,"revision":5629,"compact-revision":2891}