{"level":"warn","ts":"2026-04-09T02:00:35.810407Z","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-09T02:00:35.810559Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-09T02:00:35.810648Z","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-09T02:00:35.810671Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-09T02:00:35.810703Z","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-09T02:00:35.811373Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-04-09T02:00:35.811471Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-09T02:00:35.815453Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.747474ms"} {"level":"info","ts":"2026-04-09T02:00:35.823391Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-04-09T02:00:35.823491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:00:35.823559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-04-09T02:00:35.823618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T02:00:35.823652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-04-09T02:00:35.823704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-04-09T02:00:35.830526Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:00:35.831919Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:00:35.833159Z","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-09T02:00:35.836926Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T02:00:35.838649Z","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-09T02:00:35.838763Z","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-09T02:00:35.838776Z","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-09T02:00:35.839346Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T02:00:35.839853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-04-09T02:00:35.840284Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-09T02:00:35.841324Z","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-09T02:00:35.841528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T02:00:35.841568Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:00:35.842155Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-09T02:00:35.842203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-09T02:00:36.227768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:00:36.227812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:00:36.227833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-04-09T02:00:36.227844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:00:36.22785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-09T02:00:36.227857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-04-09T02:00:36.227865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-09T02:00:36.231685Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:36.232806Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:36.232858Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:36.232871Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:00:36.232898Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T02:00:36.233084Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:00:36.233122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:00:36.233259Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:00:36.233298Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:00:36.236972Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T02:00:36.240308Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-04-09T02:00:45.137616Z","caller":"traceutil/trace.go:171","msg":"trace[778343778] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"148.759354ms","start":"2026-04-09T02:00:44.988828Z","end":"2026-04-09T02:00:45.137587Z","steps":["trace[778343778] 'process raft request' (duration: 68.426472ms)","trace[778343778] 'compare' (duration: 80.1754ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:00:45.149123Z","caller":"traceutil/trace.go:171","msg":"trace[1566177502] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"159.508747ms","start":"2026-04-09T02:00:44.989596Z","end":"2026-04-09T02:00:45.149105Z","steps":["trace[1566177502] 'process raft request' (duration: 159.415326ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:00:45.149122Z","caller":"traceutil/trace.go:171","msg":"trace[729416599] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:351; }","duration":"130.287399ms","start":"2026-04-09T02:00:45.018812Z","end":"2026-04-09T02:00:45.1491Z","steps":["trace[729416599] 'read index received' (duration: 38.453338ms)","trace[729416599] 'applied index is now lower than readState.Index' (duration: 91.832561ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:00:45.149399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.55778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-09T02:00:45.149551Z","caller":"traceutil/trace.go:171","msg":"trace[1319705293] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:341; }","duration":"130.738343ms","start":"2026-04-09T02:00:45.018789Z","end":"2026-04-09T02:00:45.149527Z","steps":["trace[1319705293] 'agreement among raft nodes before linearized reading' (duration: 130.374169ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:08.17104Z","caller":"traceutil/trace.go:171","msg":"trace[609744219] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"155.144988ms","start":"2026-04-09T02:01:08.01585Z","end":"2026-04-09T02:01:08.170995Z","steps":["trace[609744219] 'process raft request' (duration: 154.778226ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:11.23483Z","caller":"traceutil/trace.go:171","msg":"trace[1905665369] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"104.200163ms","start":"2026-04-09T02:01:11.130592Z","end":"2026-04-09T02:01:11.234792Z","steps":["trace[1905665369] 'process raft request' (duration: 58.12041ms)","trace[1905665369] 'compare' (duration: 45.870982ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:01:11.922345Z","caller":"traceutil/trace.go:171","msg":"trace[1996350153] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"279.362928ms","start":"2026-04-09T02:01:11.64295Z","end":"2026-04-09T02:01:11.922313Z","steps":["trace[1996350153] 'process raft request' (duration: 278.742565ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:01:11.924859Z","caller":"traceutil/trace.go:171","msg":"trace[46955694] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"245.385646ms","start":"2026-04-09T02:01:11.679453Z","end":"2026-04-09T02:01:11.924839Z","steps":["trace[46955694] 'process raft request' (duration: 244.978614ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:02:28.484027Z","caller":"traceutil/trace.go:171","msg":"trace[2134068020] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"115.8336ms","start":"2026-04-09T02:02:28.368166Z","end":"2026-04-09T02:02:28.484Z","steps":["trace[2134068020] 'process raft request' (duration: 115.663346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:02:50.468576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.858622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:02:50.468753Z","caller":"traceutil/trace.go:171","msg":"trace[1085362497] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"252.19501ms","start":"2026-04-09T02:02:50.216521Z","end":"2026-04-09T02:02:50.468716Z","steps":["trace[1085362497] 'process raft request' (duration: 13.47796ms)","trace[1085362497] 'compare' (duration: 237.73913ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:02:50.631444Z","caller":"traceutil/trace.go:171","msg":"trace[283671174] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"117.596585ms","start":"2026-04-09T02:02:50.513814Z","end":"2026-04-09T02:02:50.631411Z","steps":["trace[283671174] 'read index received' (duration: 99.431871ms)","trace[283671174] 'applied index is now lower than readState.Index' (duration: 18.163034ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:02:50.631457Z","caller":"traceutil/trace.go:171","msg":"trace[80734482] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"155.380681ms","start":"2026-04-09T02:02:50.476044Z","end":"2026-04-09T02:02:50.631425Z","steps":["trace[80734482] 'process raft request' (duration: 137.212537ms)","trace[80734482] 'compare' (duration: 17.94357ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:02:50.631663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.822699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-04-09T02:02:50.631741Z","caller":"traceutil/trace.go:171","msg":"trace[1549683605] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1854; }","duration":"117.946122ms","start":"2026-04-09T02:02:50.513763Z","end":"2026-04-09T02:02:50.63171Z","steps":["trace[1549683605] 'agreement among raft nodes before linearized reading' (duration: 117.750178ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:27.408893Z","caller":"traceutil/trace.go:171","msg":"trace[45657614] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"109.01047ms","start":"2026-04-09T02:03:27.299837Z","end":"2026-04-09T02:03:27.408847Z","steps":["trace[45657614] 'process raft request' (duration: 108.837748ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:27.58333Z","caller":"traceutil/trace.go:171","msg":"trace[948016726] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"108.865968ms","start":"2026-04-09T02:03:27.474435Z","end":"2026-04-09T02:03:27.583301Z","steps":["trace[948016726] 'process raft request' (duration: 108.387952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:27.852265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.880672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:03:27.852333Z","caller":"traceutil/trace.go:171","msg":"trace[1667670218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2158; }","duration":"159.025575ms","start":"2026-04-09T02:03:27.693292Z","end":"2026-04-09T02:03:27.852318Z","steps":["trace[1667670218] 'range keys from in-memory index tree' (duration: 158.77305ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:04.584675Z","caller":"traceutil/trace.go:171","msg":"trace[1264503668] linearizableReadLoop","detail":"{readStateIndex:4699; appliedIndex:4698; }","duration":"176.214017ms","start":"2026-04-09T02:07:04.408432Z","end":"2026-04-09T02:07:04.584646Z","steps":["trace[1264503668] 'read index received' (duration: 80.464188ms)","trace[1264503668] 'applied index is now lower than readState.Index' (duration: 95.748699ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:04.584686Z","caller":"traceutil/trace.go:171","msg":"trace[680803687] transaction","detail":"{read_only:false; response_revision:4505; number_of_response:1; }","duration":"183.883079ms","start":"2026-04-09T02:07:04.400777Z","end":"2026-04-09T02:07:04.58466Z","steps":["trace[680803687] 'process raft request' (duration: 87.852873ms)","trace[680803687] 'compare' (duration: 95.874042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:04.584904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.434952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:07:04.585037Z","caller":"traceutil/trace.go:171","msg":"trace[1597351349] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:4505; }","duration":"176.584646ms","start":"2026-04-09T02:07:04.408391Z","end":"2026-04-09T02:07:04.584975Z","steps":["trace[1597351349] 'agreement among raft nodes before linearized reading' (duration: 176.371811ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:04.603698Z","caller":"traceutil/trace.go:171","msg":"trace[1670188306] transaction","detail":"{read_only:false; response_revision:4506; number_of_response:1; }","duration":"188.083691ms","start":"2026-04-09T02:07:04.415586Z","end":"2026-04-09T02:07:04.60367Z","steps":["trace[1670188306] 'process raft request' (duration: 187.932108ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:04.789615Z","caller":"traceutil/trace.go:171","msg":"trace[911109801] linearizableReadLoop","detail":"{readStateIndex:4705; appliedIndex:4704; }","duration":"108.268399ms","start":"2026-04-09T02:07:04.681322Z","end":"2026-04-09T02:07:04.78959Z","steps":["trace[911109801] 'read index received' (duration: 107.569272ms)","trace[911109801] 'applied index is now lower than readState.Index' (duration: 697.917µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:04.789646Z","caller":"traceutil/trace.go:171","msg":"trace[1115860696] transaction","detail":"{read_only:false; response_revision:4511; number_of_response:1; }","duration":"115.93893ms","start":"2026-04-09T02:07:04.673679Z","end":"2026-04-09T02:07:04.789618Z","steps":["trace[1115860696] 'process raft request' (duration: 115.060658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:04.78988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.513858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-09T02:07:04.789927Z","caller":"traceutil/trace.go:171","msg":"trace[397876695] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4511; }","duration":"150.57496ms","start":"2026-04-09T02:07:04.639336Z","end":"2026-04-09T02:07:04.789911Z","steps":["trace[397876695] 'agreement among raft nodes before linearized reading' (duration: 150.380475ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:04.809312Z","caller":"traceutil/trace.go:171","msg":"trace[40783566] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"121.863677ms","start":"2026-04-09T02:07:04.687433Z","end":"2026-04-09T02:07:04.809296Z","steps":["trace[40783566] 'process raft request' (duration: 121.782625ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:04.80933Z","caller":"traceutil/trace.go:171","msg":"trace[227272711] transaction","detail":"{read_only:false; response_revision:4512; number_of_response:1; }","duration":"122.026461ms","start":"2026-04-09T02:07:04.687277Z","end":"2026-04-09T02:07:04.809303Z","steps":["trace[227272711] 'process raft request' (duration: 121.822717ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:04.80975Z","caller":"traceutil/trace.go:171","msg":"trace[1763191010] transaction","detail":"{read_only:false; response_revision:4514; number_of_response:1; }","duration":"121.838547ms","start":"2026-04-09T02:07:04.687903Z","end":"2026-04-09T02:07:04.809742Z","steps":["trace[1763191010] 'process raft request' (duration: 121.346355ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:04.809942Z","caller":"traceutil/trace.go:171","msg":"trace[1288216601] transaction","detail":"{read_only:false; response_revision:4515; number_of_response:1; }","duration":"121.230642ms","start":"2026-04-09T02:07:04.688706Z","end":"2026-04-09T02:07:04.809936Z","steps":["trace[1288216601] 'process raft request' (duration: 120.981365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:04.811647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.041712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:07:04.811901Z","caller":"traceutil/trace.go:171","msg":"trace[116264954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4515; }","duration":"120.148494ms","start":"2026-04-09T02:07:04.69156Z","end":"2026-04-09T02:07:04.811709Z","steps":["trace[116264954] 'agreement among raft nodes before linearized reading' (duration: 120.032962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:04.812448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.282391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-040f83ef-63ce-45a2-a6c8-8df5af34c46c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-09T02:07:04.812531Z","caller":"traceutil/trace.go:171","msg":"trace[712726502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-040f83ef-63ce-45a2-a6c8-8df5af34c46c; range_end:; response_count:1; response_revision:4515; }","duration":"125.419005ms","start":"2026-04-09T02:07:04.687093Z","end":"2026-04-09T02:07:04.812512Z","steps":["trace[712726502] 'agreement among raft nodes before linearized reading' (duration: 124.262817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:07.844373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.347485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-04-09T02:07:07.844481Z","caller":"traceutil/trace.go:171","msg":"trace[925735007] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4556; }","duration":"101.534149ms","start":"2026-04-09T02:07:07.742926Z","end":"2026-04-09T02:07:07.84446Z","steps":["trace[925735007] 'range keys from in-memory index tree' (duration: 101.11708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:16.884577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.721176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-04-09T02:07:16.884661Z","caller":"traceutil/trace.go:171","msg":"trace[116462118] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4683; }","duration":"126.871261ms","start":"2026-04-09T02:07:16.757769Z","end":"2026-04-09T02:07:16.88464Z","steps":["trace[116462118] 'range keys from in-memory index tree' (duration: 126.521422ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:07:17.004444Z","caller":"traceutil/trace.go:171","msg":"trace[1328237566] linearizableReadLoop","detail":"{readStateIndex:4886; appliedIndex:4885; }","duration":"106.381227ms","start":"2026-04-09T02:07:16.898033Z","end":"2026-04-09T02:07:17.004414Z","steps":["trace[1328237566] 'read index received' (duration: 106.193603ms)","trace[1328237566] 'applied index is now lower than readState.Index' (duration: 185.904µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:07:17.00461Z","caller":"traceutil/trace.go:171","msg":"trace[983025844] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"109.90839ms","start":"2026-04-09T02:07:16.894685Z","end":"2026-04-09T02:07:17.004593Z","steps":["trace[983025844] 'process raft request' (duration: 109.524631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:17.004921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.873209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-09T02:07:17.004979Z","caller":"traceutil/trace.go:171","msg":"trace[295105315] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4684; }","duration":"106.960061ms","start":"2026-04-09T02:07:16.897997Z","end":"2026-04-09T02:07:17.004957Z","steps":["trace[295105315] 'agreement among raft nodes before linearized reading' (duration: 106.768926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:17.005227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.591694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T02:07:17.005351Z","caller":"traceutil/trace.go:171","msg":"trace[1902452106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4684; }","duration":"101.764429ms","start":"2026-04-09T02:07:16.903559Z","end":"2026-04-09T02:07:17.005323Z","steps":["trace[1902452106] 'agreement among raft nodes before linearized reading' (duration: 101.340068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:07:17.226671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.479391ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:07:17.226808Z","caller":"traceutil/trace.go:171","msg":"trace[300549651] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"150.329418ms","start":"2026-04-09T02:07:17.076451Z","end":"2026-04-09T02:07:17.22678Z","steps":["trace[300549651] 'process raft request' (duration: 31.599601ms)","trace[300549651] 'compare' (duration: 118.330528ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:07:17.618158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.477365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-09T02:07:17.618245Z","caller":"traceutil/trace.go:171","msg":"trace[2042968547] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4694; }","duration":"108.618919ms","start":"2026-04-09T02:07:17.509606Z","end":"2026-04-09T02:07:17.618225Z","steps":["trace[2042968547] 'range keys from in-memory index tree' (duration: 108.289141ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:10:36.294369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3228} {"level":"info","ts":"2026-04-09T02:10:36.387334Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3228,"took":"91.113848ms","hash":3250434410,"current-db-size-bytes":59179008,"current-db-size":"59 MB","current-db-size-in-use-bytes":32219136,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-09T02:10:36.387442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3250434410,"revision":3228,"compact-revision":-1}