{"level":"warn","ts":"2026-03-19T09:00:39.945048Z","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-19T09:00:39.945123Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.122: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.122:2380","--initial-cluster=instance=https://199.19.213.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.122: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-19T09:00:39.945183Z","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-19T09:00:39.945191Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.122:2380"]}
{"level":"info","ts":"2026-03-19T09:00:39.945205Z","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-19T09:00:39.945781Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"]}
{"level":"info","ts":"2026-03-19T09:00:39.945851Z","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.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.122: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-19T09:00:39.95433Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.244374ms"}
{"level":"info","ts":"2026-03-19T09:00:39.966333Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f9ba8c11acfbc749","cluster-id":"ca7c3e103d4cd533"}
{"level":"info","ts":"2026-03-19T09:00:39.966416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-19T09:00:39.966436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 0"}
{"level":"info","ts":"2026-03-19T09:00:39.966447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f9ba8c11acfbc749 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-19T09:00:39.966461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 1"}
{"level":"info","ts":"2026-03-19T09:00:39.966499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"}
{"level":"warn","ts":"2026-03-19T09:00:39.982382Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-19T09:00:39.984443Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-19T09:00:39.986906Z","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-19T09:00:39.990348Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f9ba8c11acfbc749","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-19T09:00:39.990531Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f9ba8c11acfbc749","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-19T09:00:39.990676Z","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-19T09:00:39.990747Z","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-19T09:00:39.990767Z","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-19T09:00:39.993316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"}
{"level":"info","ts":"2026-03-19T09:00:39.995394Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","added-peer-id":"f9ba8c11acfbc749","added-peer-peer-urls":["https://199.19.213.122:2380"]}
{"level":"info","ts":"2026-03-19T09:00:39.996648Z","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-19T09:00:39.996794Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.122:2380"}
{"level":"info","ts":"2026-03-19T09:00:39.996832Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.122:2380"}
{"level":"info","ts":"2026-03-19T09:00:39.997043Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f9ba8c11acfbc749","initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-19T09:00:39.997114Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-19T09:00:40.669381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-19T09:00:40.669447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-19T09:00:40.669476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgPreVoteResp from f9ba8c11acfbc749 at term 1"}
{"level":"info","ts":"2026-03-19T09:00:40.669492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became candidate at term 2"}
{"level":"info","ts":"2026-03-19T09:00:40.669504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgVoteResp from f9ba8c11acfbc749 at term 2"}
{"level":"info","ts":"2026-03-19T09:00:40.669515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became leader at term 2"}
{"level":"info","ts":"2026-03-19T09:00:40.669528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f9ba8c11acfbc749 elected leader f9ba8c11acfbc749 at term 2"}
{"level":"info","ts":"2026-03-19T09:00:40.677829Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f9ba8c11acfbc749","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.122:2379]}","request-path":"/0/members/f9ba8c11acfbc749/attributes","cluster-id":"ca7c3e103d4cd533","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-19T09:00:40.677853Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-19T09:00:40.678054Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T09:00:40.678291Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-19T09:00:40.67834Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-19T09:00:40.68171Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-19T09:00:40.678087Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-19T09:00:40.682678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T09:00:40.682798Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T09:00:40.682833Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-19T09:00:40.685124Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.122:2379"}
{"level":"info","ts":"2026-03-19T09:00:55.126149Z","caller":"traceutil/trace.go:171","msg":"trace[1940007203] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"146.378617ms","start":"2026-03-19T09:00:54.979743Z","end":"2026-03-19T09:00:55.126121Z","steps":["trace[1940007203] 'process raft request'  (duration: 146.181762ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:00:55.720654Z","caller":"traceutil/trace.go:171","msg":"trace[341984031] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"106.286181ms","start":"2026-03-19T09:00:55.614345Z","end":"2026-03-19T09:00:55.720631Z","steps":["trace[341984031] 'process raft request'  (duration: 105.88985ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:01:13.129681Z","caller":"traceutil/trace.go:171","msg":"trace[1222984807] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"121.822743ms","start":"2026-03-19T09:01:13.007811Z","end":"2026-03-19T09:01:13.129634Z","steps":["trace[1222984807] 'process raft request'  (duration: 121.591856ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:01:16.322933Z","caller":"traceutil/trace.go:171","msg":"trace[1541145278] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"126.843039ms","start":"2026-03-19T09:01:16.196058Z","end":"2026-03-19T09:01:16.322901Z","steps":["trace[1541145278] 'process raft request'  (duration: 119.17743ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:01:16.909701Z","caller":"traceutil/trace.go:171","msg":"trace[1434036947] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"223.132442ms","start":"2026-03-19T09:01:16.686539Z","end":"2026-03-19T09:01:16.909671Z","steps":["trace[1434036947] 'process raft request'  (duration: 222.833224ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:02:32.563246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.737713ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T09:02:32.563429Z","caller":"traceutil/trace.go:171","msg":"trace[372550555] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1564; }","duration":"186.992289ms","start":"2026-03-19T09:02:32.376414Z","end":"2026-03-19T09:02:32.563406Z","steps":["trace[372550555] 'range keys from in-memory index tree'  (duration: 186.711261ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:02:33.029477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.343814ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14360181533045860976 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:1543 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T09:02:33.029744Z","caller":"traceutil/trace.go:171","msg":"trace[2021062575] transaction","detail":"{read_only:false; response_revision:1567; number_of_response:1; }","duration":"178.046838ms","start":"2026-03-19T09:02:32.851653Z","end":"2026-03-19T09:02:33.029699Z","steps":["trace[2021062575] 'process raft request'  (duration: 75.767859ms)","trace[2021062575] 'compare'  (duration: 101.221441ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:03:11.294571Z","caller":"traceutil/trace.go:171","msg":"trace[610475356] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"120.020064ms","start":"2026-03-19T09:03:11.174523Z","end":"2026-03-19T09:03:11.294543Z","steps":["trace[610475356] 'process raft request'  (duration: 119.833869ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:03:11.313923Z","caller":"traceutil/trace.go:171","msg":"trace[595390556] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"103.638496ms","start":"2026-03-19T09:03:11.210219Z","end":"2026-03-19T09:03:11.313858Z","steps":["trace[595390556] 'process raft request'  (duration: 103.4064ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:03:23.162477Z","caller":"traceutil/trace.go:171","msg":"trace[2115080475] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"129.759275ms","start":"2026-03-19T09:03:23.032685Z","end":"2026-03-19T09:03:23.162444Z","steps":["trace[2115080475] 'process raft request'  (duration: 117.364494ms)","trace[2115080475] 'compare'  (duration: 11.95652ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T09:04:52.633214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.527194ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T09:04:52.633345Z","caller":"traceutil/trace.go:171","msg":"trace[1142106918] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2495; }","duration":"257.667648ms","start":"2026-03-19T09:04:52.37566Z","end":"2026-03-19T09:04:52.633328Z","steps":["trace[1142106918] 'range keys from in-memory index tree'  (duration: 257.513834ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:04:52.63372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.62552ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14360181533045863242 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2486 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T09:04:52.633787Z","caller":"traceutil/trace.go:171","msg":"trace[1345448484] linearizableReadLoop","detail":"{readStateIndex:2600; appliedIndex:2599; }","duration":"156.881587ms","start":"2026-03-19T09:04:52.476895Z","end":"2026-03-19T09:04:52.633777Z","steps":["trace[1345448484] 'read index received'  (duration: 39.541µs)","trace[1345448484] 'applied index is now lower than readState.Index'  (duration: 156.841116ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:04:52.633899Z","caller":"traceutil/trace.go:171","msg":"trace[2000991877] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"340.46808ms","start":"2026-03-19T09:04:52.293406Z","end":"2026-03-19T09:04:52.633875Z","steps":["trace[2000991877] 'process raft request'  (duration: 90.624565ms)","trace[2000991877] 'compare'  (duration: 249.320481ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T09:04:52.634009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:04:52.293388Z","time spent":"340.571973ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:2486 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-03-19T09:04:52.634222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.312389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-03-19T09:04:52.634286Z","caller":"traceutil/trace.go:171","msg":"trace[623413664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2496; }","duration":"157.429162ms","start":"2026-03-19T09:04:52.476842Z","end":"2026-03-19T09:04:52.634271Z","steps":["trace[623413664] 'agreement among raft nodes before linearized reading'  (duration: 157.289708ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:07:18.298371Z","caller":"traceutil/trace.go:171","msg":"trace[869797089] transaction","detail":"{read_only:false; response_revision:3391; number_of_response:1; }","duration":"134.066524ms","start":"2026-03-19T09:07:18.164277Z","end":"2026-03-19T09:07:18.298344Z","steps":["trace[869797089] 'process raft request'  (duration: 133.767266ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:07:18.454674Z","caller":"traceutil/trace.go:171","msg":"trace[60115455] linearizableReadLoop","detail":"{readStateIndex:3532; appliedIndex:3531; }","duration":"128.453183ms","start":"2026-03-19T09:07:18.326198Z","end":"2026-03-19T09:07:18.454651Z","steps":["trace[60115455] 'read index received'  (duration: 125.589615ms)","trace[60115455] 'applied index is now lower than readState.Index'  (duration: 2.862318ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:07:18.454763Z","caller":"traceutil/trace.go:171","msg":"trace[1409295362] transaction","detail":"{read_only:false; response_revision:3393; number_of_response:1; }","duration":"129.517963ms","start":"2026-03-19T09:07:18.325206Z","end":"2026-03-19T09:07:18.454724Z","steps":["trace[1409295362] 'process raft request'  (duration: 126.651795ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:07:18.45487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.661369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:15668"}
{"level":"info","ts":"2026-03-19T09:07:18.454956Z","caller":"traceutil/trace.go:171","msg":"trace[284213383] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:3393; }","duration":"128.772381ms","start":"2026-03-19T09:07:18.326166Z","end":"2026-03-19T09:07:18.454938Z","steps":["trace[284213383] 'agreement among raft nodes before linearized reading'  (duration: 128.569936ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:08:41.821867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.389236ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14360181533045868262 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:4274 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:415 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-19T09:08:41.821986Z","caller":"traceutil/trace.go:171","msg":"trace[1484752616] linearizableReadLoop","detail":"{readStateIndex:4695; appliedIndex:4694; }","duration":"106.570821ms","start":"2026-03-19T09:08:41.715397Z","end":"2026-03-19T09:08:41.821968Z","steps":["trace[1484752616] 'read index received'  (duration: 37.541µs)","trace[1484752616] 'applied index is now lower than readState.Index'  (duration: 106.53207ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:08:41.822058Z","caller":"traceutil/trace.go:171","msg":"trace[1168046625] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"181.843241ms","start":"2026-03-19T09:08:41.640174Z","end":"2026-03-19T09:08:41.822018Z","steps":["trace[1168046625] 'process raft request'  (duration: 59.946011ms)","trace[1168046625] 'compare'  (duration: 120.972545ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T09:08:41.822154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.746056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7bcf17e-3772-45cd-aec4-009de81b1244\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-03-19T09:08:41.822222Z","caller":"traceutil/trace.go:171","msg":"trace[202948584] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7bcf17e-3772-45cd-aec4-009de81b1244; range_end:; response_count:1; response_revision:4511; }","duration":"106.858ms","start":"2026-03-19T09:08:41.715349Z","end":"2026-03-19T09:08:41.822207Z","steps":["trace[202948584] 'agreement among raft nodes before linearized reading'  (duration: 106.672375ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:08:53.761448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.545094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-94wlf.189e32f2246fa47b\" ","response":"range_response_count:1 size:906"}
{"level":"info","ts":"2026-03-19T09:08:53.761544Z","caller":"traceutil/trace.go:171","msg":"trace[324107249] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-94wlf.189e32f2246fa47b; range_end:; response_count:1; response_revision:4739; }","duration":"233.695259ms","start":"2026-03-19T09:08:53.527827Z","end":"2026-03-19T09:08:53.761522Z","steps":["trace[324107249] 'range keys from in-memory index tree'  (duration: 233.315648ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:08:53.761996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.532206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-03-19T09:08:53.762085Z","caller":"traceutil/trace.go:171","msg":"trace[1343616948] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:4739; }","duration":"142.662529ms","start":"2026-03-19T09:08:53.619399Z","end":"2026-03-19T09:08:53.762062Z","steps":["trace[1343616948] 'range keys from in-memory index tree'  (duration: 142.334351ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:08:53.762163Z","caller":"traceutil/trace.go:171","msg":"trace[240159368] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"122.583305ms","start":"2026-03-19T09:08:53.639556Z","end":"2026-03-19T09:08:53.762139Z","steps":["trace[240159368] 'process raft request'  (duration: 99.386216ms)","trace[240159368] 'compare'  (duration: 22.736827ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:09:01.335823Z","caller":"traceutil/trace.go:171","msg":"trace[1806821712] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5098; }","duration":"181.273026ms","start":"2026-03-19T09:09:01.154525Z","end":"2026-03-19T09:09:01.335798Z","steps":["trace[1806821712] 'read index received'  (duration: 87.42341ms)","trace[1806821712] 'applied index is now lower than readState.Index'  (duration: 93.848596ms)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:09:01.336112Z","caller":"traceutil/trace.go:171","msg":"trace[720109597] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"191.598627ms","start":"2026-03-19T09:09:01.144498Z","end":"2026-03-19T09:09:01.336097Z","steps":["trace[720109597] 'process raft request'  (duration: 97.523065ms)","trace[720109597] 'compare'  (duration: 93.667022ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T09:09:01.336345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.805841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-19T09:09:01.336392Z","caller":"traceutil/trace.go:171","msg":"trace[1445824024] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4902; }","duration":"181.883903ms","start":"2026-03-19T09:09:01.154495Z","end":"2026-03-19T09:09:01.336379Z","steps":["trace[1445824024] 'agreement among raft nodes before linearized reading'  (duration: 181.796371ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:09:01.33656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.925232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cf5ae93a-21c8-4a14-a28f-f31c29687d72\" ","response":"range_response_count:1 size:3766"}
{"level":"info","ts":"2026-03-19T09:09:01.336598Z","caller":"traceutil/trace.go:171","msg":"trace[983247082] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cf5ae93a-21c8-4a14-a28f-f31c29687d72; range_end:; response_count:1; response_revision:4902; }","duration":"130.962043ms","start":"2026-03-19T09:09:01.205626Z","end":"2026-03-19T09:09:01.336588Z","steps":["trace[983247082] 'agreement among raft nodes before linearized reading'  (duration: 130.904891ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:10:40.703885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2734}
{"level":"info","ts":"2026-03-19T09:10:40.764363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2734,"took":"58.918438ms","hash":608413236,"current-db-size-bytes":41324544,"current-db-size":"41 MB","current-db-size-in-use-bytes":31072256,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-03-19T09:10:40.764429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":608413236,"revision":2734,"compact-revision":-1}
{"level":"info","ts":"2026-03-19T09:14:52.208941Z","caller":"traceutil/trace.go:171","msg":"trace[104009144] linearizableReadLoop","detail":"{readStateIndex:7765; appliedIndex:7764; }","duration":"256.490494ms","start":"2026-03-19T09:14:51.952414Z","end":"2026-03-19T09:14:52.208904Z","steps":["trace[104009144] 'read index received'  (duration: 256.150464ms)","trace[104009144] 'applied index is now lower than readState.Index'  (duration: 338.33µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:14:52.20901Z","caller":"traceutil/trace.go:171","msg":"trace[979065905] transaction","detail":"{read_only:false; response_revision:7486; number_of_response:1; }","duration":"362.526177ms","start":"2026-03-19T09:14:51.846445Z","end":"2026-03-19T09:14:52.208971Z","steps":["trace[979065905] 'process raft request'  (duration: 361.979431ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:14:52.209149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.711009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-03-19T09:14:52.209186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:14:51.846412Z","time spent":"362.68811ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:7477 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-03-19T09:14:52.209249Z","caller":"traceutil/trace.go:171","msg":"trace[634391446] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:7486; }","duration":"256.863563ms","start":"2026-03-19T09:14:51.952366Z","end":"2026-03-19T09:14:52.209229Z","steps":["trace[634391446] 'agreement among raft nodes before linearized reading'  (duration: 256.687849ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:14:52.210891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.27971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rqdh7\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-03-19T09:14:52.210974Z","caller":"traceutil/trace.go:171","msg":"trace[853566848] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rqdh7; range_end:; response_count:1; response_revision:7488; }","duration":"182.412394ms","start":"2026-03-19T09:14:52.028543Z","end":"2026-03-19T09:14:52.210956Z","steps":["trace[853566848] 'agreement among raft nodes before linearized reading'  (duration: 182.198218ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:04.699665Z","caller":"traceutil/trace.go:171","msg":"trace[1323514008] transaction","detail":"{read_only:false; response_revision:7545; number_of_response:1; }","duration":"316.300508ms","start":"2026-03-19T09:15:04.383336Z","end":"2026-03-19T09:15:04.699637Z","steps":["trace[1323514008] 'process raft request'  (duration: 274.11707ms)","trace[1323514008] 'compare'  (duration: 41.286374ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T09:15:04.699815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:04.383314Z","time spent":"316.423691ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" mod_revision:7474 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" value_size:467 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" > >"}
{"level":"info","ts":"2026-03-19T09:15:32.23644Z","caller":"traceutil/trace.go:171","msg":"trace[2002233401] transaction","detail":"{read_only:false; response_revision:7733; number_of_response:1; }","duration":"245.560249ms","start":"2026-03-19T09:15:31.990858Z","end":"2026-03-19T09:15:32.236418Z","steps":["trace[2002233401] 'process raft request'  (duration: 245.443526ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:35.603672Z","caller":"traceutil/trace.go:171","msg":"trace[711439344] linearizableReadLoop","detail":"{readStateIndex:8040; appliedIndex:8039; }","duration":"195.865097ms","start":"2026-03-19T09:15:35.40778Z","end":"2026-03-19T09:15:35.603646Z","steps":["trace[711439344] 'read index received'  (duration: 195.701233ms)","trace[711439344] 'applied index is now lower than readState.Index'  (duration: 162.124µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:15:35.603841Z","caller":"traceutil/trace.go:171","msg":"trace[812562158] transaction","detail":"{read_only:false; response_revision:7749; number_of_response:1; }","duration":"260.182073ms","start":"2026-03-19T09:15:35.343602Z","end":"2026-03-19T09:15:35.603784Z","steps":["trace[812562158] 'process raft request'  (duration: 259.874975ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:35.603973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.152725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-03-19T09:15:35.60404Z","caller":"traceutil/trace.go:171","msg":"trace[601127918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7749; }","duration":"196.292928ms","start":"2026-03-19T09:15:35.407731Z","end":"2026-03-19T09:15:35.604024Z","steps":["trace[601127918] 'agreement among raft nodes before linearized reading'  (duration: 196.085393ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:35.604112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.035927ms","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":"warn","ts":"2026-03-19T09:15:35.604196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.574316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-03-19T09:15:35.604265Z","caller":"traceutil/trace.go:171","msg":"trace[1785581086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7749; }","duration":"189.646878ms","start":"2026-03-19T09:15:35.4146Z","end":"2026-03-19T09:15:35.604247Z","steps":["trace[1785581086] 'agreement among raft nodes before linearized reading'  (duration: 189.486704ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:35.604221Z","caller":"traceutil/trace.go:171","msg":"trace[2134449429] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7749; }","duration":"194.187031ms","start":"2026-03-19T09:15:35.41001Z","end":"2026-03-19T09:15:35.604197Z","steps":["trace[2134449429] 'agreement among raft nodes before linearized reading'  (duration: 193.864402ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:35.750353Z","caller":"traceutil/trace.go:171","msg":"trace[1553501541] transaction","detail":"{read_only:false; response_revision:7752; number_of_response:1; }","duration":"140.981065ms","start":"2026-03-19T09:15:35.609302Z","end":"2026-03-19T09:15:35.750283Z","steps":["trace[1553501541] 'process raft request'  (duration: 140.930994ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:35.750402Z","caller":"traceutil/trace.go:171","msg":"trace[940191219] transaction","detail":"{read_only:false; response_revision:7751; number_of_response:1; }","duration":"141.18032ms","start":"2026-03-19T09:15:35.609184Z","end":"2026-03-19T09:15:35.750364Z","steps":["trace[940191219] 'process raft request'  (duration: 140.943594ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:35.750455Z","caller":"traceutil/trace.go:171","msg":"trace[1731213257] transaction","detail":"{read_only:false; response_revision:7753; number_of_response:1; }","duration":"141.081747ms","start":"2026-03-19T09:15:35.60936Z","end":"2026-03-19T09:15:35.750442Z","steps":["trace[1731213257] 'process raft request'  (duration: 140.901183ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:40.711242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5743}
{"level":"info","ts":"2026-03-19T09:15:40.806298Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5743,"took":"93.295568ms","hash":623153307,"current-db-size-bytes":43003904,"current-db-size":"43 MB","current-db-size-in-use-bytes":23035904,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-03-19T09:15:40.806369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":623153307,"revision":5743,"compact-revision":2734}
{"level":"info","ts":"2026-03-19T09:15:42.502418Z","caller":"traceutil/trace.go:171","msg":"trace[1546701996] linearizableReadLoop","detail":"{readStateIndex:8109; appliedIndex:8108; }","duration":"178.798986ms","start":"2026-03-19T09:15:42.323592Z","end":"2026-03-19T09:15:42.502391Z","steps":["trace[1546701996] 'read index received'  (duration: 178.656412ms)","trace[1546701996] 'applied index is now lower than readState.Index'  (duration: 141.494µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T09:15:42.502568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.959531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T09:15:42.502601Z","caller":"traceutil/trace.go:171","msg":"trace[1692480624] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:7813; }","duration":"179.014823ms","start":"2026-03-19T09:15:42.323575Z","end":"2026-03-19T09:15:42.502589Z","steps":["trace[1692480624] 'agreement among raft nodes before linearized reading'  (duration: 178.92043ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:42.502925Z","caller":"traceutil/trace.go:171","msg":"trace[1513910156] transaction","detail":"{read_only:false; response_revision:7813; number_of_response:1; }","duration":"179.824054ms","start":"2026-03-19T09:15:42.32309Z","end":"2026-03-19T09:15:42.502914Z","steps":["trace[1513910156] 'process raft request'  (duration: 179.120965ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:46.154399Z","caller":"traceutil/trace.go:171","msg":"trace[831846908] transaction","detail":"{read_only:false; response_revision:7850; number_of_response:1; }","duration":"223.526064ms","start":"2026-03-19T09:15:45.930849Z","end":"2026-03-19T09:15:46.154375Z","steps":["trace[831846908] 'process raft request'  (duration: 223.38486ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:56.113901Z","caller":"traceutil/trace.go:171","msg":"trace[899653825] linearizableReadLoop","detail":"{readStateIndex:8213; appliedIndex:8212; }","duration":"313.499521ms","start":"2026-03-19T09:15:55.800383Z","end":"2026-03-19T09:15:56.113882Z","steps":["trace[899653825] 'read index received'  (duration: 313.335987ms)","trace[899653825] 'applied index is now lower than readState.Index'  (duration: 162.944µs)"],"step_count":2}
{"level":"info","ts":"2026-03-19T09:15:56.114Z","caller":"traceutil/trace.go:171","msg":"trace[846053919] transaction","detail":"{read_only:false; response_revision:7914; number_of_response:1; }","duration":"406.212405ms","start":"2026-03-19T09:15:55.707779Z","end":"2026-03-19T09:15:56.113991Z","steps":["trace[846053919] 'process raft request'  (duration: 405.984589ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:56.114075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:55.707764Z","time spent":"406.255607ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:7906 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-03-19T09:15:56.114227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.956614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-03-19T09:15:56.114252Z","caller":"traceutil/trace.go:171","msg":"trace[16139525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7914; }","duration":"241.000966ms","start":"2026-03-19T09:15:55.873245Z","end":"2026-03-19T09:15:56.114246Z","steps":["trace[16139525] 'agreement among raft nodes before linearized reading'  (duration: 240.909383ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:56.114375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.297642ms","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":"warn","ts":"2026-03-19T09:15:56.114372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.037271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-03-19T09:15:56.114551Z","caller":"traceutil/trace.go:171","msg":"trace[1150294347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7914; }","duration":"263.364959ms","start":"2026-03-19T09:15:55.851166Z","end":"2026-03-19T09:15:56.114531Z","steps":["trace[1150294347] 'agreement among raft nodes before linearized reading'  (duration: 262.907217ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:56.114476Z","caller":"traceutil/trace.go:171","msg":"trace[1482148835] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7914; }","duration":"255.428855ms","start":"2026-03-19T09:15:55.85902Z","end":"2026-03-19T09:15:56.114449Z","steps":["trace[1482148835] 'agreement among raft nodes before linearized reading'  (duration: 255.21418ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:56.114714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.227832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-03-19T09:15:56.114937Z","caller":"traceutil/trace.go:171","msg":"trace[1273428853] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7914; }","duration":"314.500839ms","start":"2026-03-19T09:15:55.800343Z","end":"2026-03-19T09:15:56.114844Z","steps":["trace[1273428853] 'agreement among raft nodes before linearized reading'  (duration: 313.943604ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:56.115013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:55.800327Z","time spent":"314.661914ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-03-19T09:15:56.621209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360181533045877638,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-19T09:15:57.122174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360181533045877638,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-19T09:15:57.206283Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08885174s","expected-duration":"1s"}
{"level":"info","ts":"2026-03-19T09:15:57.208202Z","caller":"traceutil/trace.go:171","msg":"trace[1765940858] transaction","detail":"{read_only:false; response_revision:7915; number_of_response:1; }","duration":"1.090839174s","start":"2026-03-19T09:15:56.117325Z","end":"2026-03-19T09:15:57.208164Z","steps":["trace[1765940858] 'process raft request'  (duration: 1.089149019s)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:57.208399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.117302Z","time spent":"1.090995849s","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:7910 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-19T09:15:57.211786Z","caller":"traceutil/trace.go:171","msg":"trace[2109606951] transaction","detail":"{read_only:false; response_revision:7917; number_of_response:1; }","duration":"1.093208089s","start":"2026-03-19T09:15:56.11856Z","end":"2026-03-19T09:15:57.211768Z","steps":["trace[2109606951] 'process raft request'  (duration: 1.093153947s)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:57.211787Z","caller":"traceutil/trace.go:171","msg":"trace[1267714900] transaction","detail":"{read_only:false; response_revision:7916; number_of_response:1; }","duration":"1.093402984s","start":"2026-03-19T09:15:56.118354Z","end":"2026-03-19T09:15:57.211757Z","steps":["trace[1267714900] 'process raft request'  (duration: 1.093265031s)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:57.211897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.118547Z","time spent":"1.09327919s","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:7907 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-03-19T09:15:57.211918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.118333Z","time spent":"1.093512687s","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:7908 > 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\" > >"}
{"level":"info","ts":"2026-03-19T09:15:57.212317Z","caller":"traceutil/trace.go:171","msg":"trace[217899608] transaction","detail":"{read_only:false; response_revision:7919; number_of_response:1; }","duration":"638.393233ms","start":"2026-03-19T09:15:56.573897Z","end":"2026-03-19T09:15:57.21229Z","steps":["trace[217899608] 'process raft request'  (duration: 638.064533ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:57.212323Z","caller":"traceutil/trace.go:171","msg":"trace[7125150] linearizableReadLoop","detail":"{readStateIndex:8218; appliedIndex:8213; }","duration":"1.091147574s","start":"2026-03-19T09:15:56.121133Z","end":"2026-03-19T09:15:57.21228Z","steps":["trace[7125150] 'read index received'  (duration: 1.085367037s)","trace[7125150] 'applied index is now lower than readState.Index'  (duration: 5.777087ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-19T09:15:57.212425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.573872Z","time spent":"638.500785ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:7911 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-19T09:15:57.212451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091296008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-19T09:15:57.21253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.919674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-03-19T09:15:57.212558Z","caller":"traceutil/trace.go:171","msg":"trace[73780449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7919; }","duration":"200.983806ms","start":"2026-03-19T09:15:57.011565Z","end":"2026-03-19T09:15:57.212549Z","steps":["trace[73780449] 'agreement among raft nodes before linearized reading'  (duration: 200.895643ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:57.212552Z","caller":"traceutil/trace.go:171","msg":"trace[390681471] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:7919; }","duration":"1.091425802s","start":"2026-03-19T09:15:56.121101Z","end":"2026-03-19T09:15:57.212526Z","steps":["trace[390681471] 'agreement among raft nodes before linearized reading'  (duration: 1.091272768s)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:57.212603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.121092Z","time spent":"1.091501904s","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "}
{"level":"warn","ts":"2026-03-19T09:15:57.212521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059026086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-19T09:15:57.212648Z","caller":"traceutil/trace.go:171","msg":"trace[1983695374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7919; }","duration":"1.059204821s","start":"2026-03-19T09:15:56.153438Z","end":"2026-03-19T09:15:57.212643Z","steps":["trace[1983695374] 'agreement among raft nodes before linearized reading'  (duration: 1.059021586s)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:57.212624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.994178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rqdh7\" ","response":"range_response_count:1 size:6524"}
{"level":"warn","ts":"2026-03-19T09:15:57.212665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.15342Z","time spent":"1.059239512s","remote":"127.0.0.1:34548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-19T09:15:57.212662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.008948ms","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-19T09:15:57.212687Z","caller":"traceutil/trace.go:171","msg":"trace[900484661] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rqdh7; range_end:; response_count:1; response_revision:7919; }","duration":"473.126702ms","start":"2026-03-19T09:15:56.739544Z","end":"2026-03-19T09:15:57.212671Z","steps":["trace[900484661] 'agreement among raft nodes before linearized reading'  (duration: 472.938917ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:15:57.21271Z","caller":"traceutil/trace.go:171","msg":"trace[676245203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7919; }","duration":"483.08858ms","start":"2026-03-19T09:15:56.729606Z","end":"2026-03-19T09:15:57.212694Z","steps":["trace[676245203] 'agreement among raft nodes before linearized reading'  (duration: 482.942826ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:57.212726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.739507Z","time spent":"473.211014ms","remote":"127.0.0.1:34756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-rqdh7\" "}
{"level":"warn","ts":"2026-03-19T09:15:57.212751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.729584Z","time spent":"483.158583ms","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-03-19T09:15:57.212804Z","caller":"traceutil/trace.go:171","msg":"trace[1588633011] transaction","detail":"{read_only:false; response_revision:7918; number_of_response:1; }","duration":"1.09323121s","start":"2026-03-19T09:15:56.119012Z","end":"2026-03-19T09:15:57.212243Z","steps":["trace[1588633011] 'process raft request'  (duration: 1.092723766s)"],"step_count":1}
{"level":"warn","ts":"2026-03-19T09:15:57.212885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:15:56.119003Z","time spent":"1.093844136s","remote":"127.0.0.1:34824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:7909 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-03-19T09:19:18.504625Z","caller":"traceutil/trace.go:171","msg":"trace[350798681] transaction","detail":"{read_only:false; response_revision:9522; number_of_response:1; }","duration":"120.518083ms","start":"2026-03-19T09:19:18.384082Z","end":"2026-03-19T09:19:18.5046Z","steps":["trace[350798681] 'process raft request'  (duration: 112.728523ms)"],"step_count":1}
{"level":"info","ts":"2026-03-19T09:19:36.274841Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f9ba8c11acfbc749","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-19T09:19:36.281127Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-03-19T09:19:36.281202Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-03-19T09:20:40.720057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7803}
{"level":"info","ts":"2026-03-19T09:20:40.78253Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7803,"took":"60.00348ms","hash":2659985082,"current-db-size-bytes":43003904,"current-db-size":"43 MB","current-db-size-in-use-bytes":24985600,"current-db-size-in-use":"25 MB"}
{"level":"info","ts":"2026-03-19T09:20:40.782584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2659985082,"revision":7803,"compact-revision":5743}
{"level":"info","ts":"2026-03-19T09:25:40.729548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9964}
{"level":"info","ts":"2026-03-19T09:25:40.806604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9964,"took":"75.391897ms","hash":3455754937,"current-db-size-bytes":43003904,"current-db-size":"43 MB","current-db-size-in-use-bytes":16654336,"current-db-size-in-use":"17 MB"}
{"level":"info","ts":"2026-03-19T09:25:40.806654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3455754937,"revision":9964,"compact-revision":7803}