{"level":"warn","ts":"2026-03-20T05:34:10.892761Z","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-20T05:34:10.892849Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-20T05:34:10.892913Z","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-20T05:34:10.892925Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-20T05:34:10.892943Z","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-20T05:34:10.893723Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-03-20T05:34:10.893805Z","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://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-20T05:34:10.897495Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.472285ms"} {"level":"info","ts":"2026-03-20T05:34:10.905521Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-03-20T05:34:10.905604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T05:34:10.905627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-03-20T05:34:10.905645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T05:34:10.905658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-03-20T05:34:10.905726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-03-20T05:34:10.911022Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T05:34:10.913258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T05:34:10.91648Z","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-20T05:34:10.920154Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T05:34:10.920301Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T05:34:10.920367Z","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-20T05:34:10.920394Z","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-20T05:34:10.920402Z","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-20T05:34:10.925072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-03-20T05:34:10.925357Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-03-20T05:34:10.9264Z","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-20T05:34:10.926531Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-20T05:34:10.926592Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-03-20T05:34:10.926858Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T05:34:10.926911Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T05:34:11.40651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T05:34:11.406575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T05:34:11.406617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-03-20T05:34:11.40665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-03-20T05:34:11.406662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-20T05:34:11.406679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-03-20T05:34:11.406693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-03-20T05:34:11.408195Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T05:34:11.408176Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:34:11.408206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:34:11.408316Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:34:11.408824Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T05:34:11.40902Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T05:34:11.409548Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:34:11.40968Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:34:11.409721Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:34:11.41218Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-03-20T05:34:11.412294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-20T05:35:15.898614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.450947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:35:15.898774Z","caller":"traceutil/trace.go:171","msg":"trace[1192073504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"151.721805ms","start":"2026-03-20T05:35:15.747024Z","end":"2026-03-20T05:35:15.898746Z","steps":["trace[1192073504] 'range keys from in-memory index tree' (duration: 151.235461ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:35:41.892012Z","caller":"traceutil/trace.go:171","msg":"trace[641961107] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"102.922238ms","start":"2026-03-20T05:35:41.789064Z","end":"2026-03-20T05:35:41.891986Z","steps":["trace[641961107] 'process raft request' (duration: 102.757114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:36:45.751855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.047249ms","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-03-20T05:36:45.751924Z","caller":"traceutil/trace.go:171","msg":"trace[1760379298] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1869; }","duration":"208.168323ms","start":"2026-03-20T05:36:45.543742Z","end":"2026-03-20T05:36:45.751911Z","steps":["trace[1760379298] 'range keys from in-memory index tree' (duration: 207.892425ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:36:45.872035Z","caller":"traceutil/trace.go:171","msg":"trace[442795047] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"113.266776ms","start":"2026-03-20T05:36:45.758742Z","end":"2026-03-20T05:36:45.872008Z","steps":["trace[442795047] 'process raft request' (duration: 113.011949ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:25.364864Z","caller":"traceutil/trace.go:171","msg":"trace[1858982346] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"133.993773ms","start":"2026-03-20T05:37:25.230846Z","end":"2026-03-20T05:37:25.36484Z","steps":["trace[1858982346] 'process raft request' (duration: 133.86686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:37:58.497337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.735454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:37:58.497457Z","caller":"traceutil/trace.go:171","msg":"trace[1065948351] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2385; }","duration":"103.927739ms","start":"2026-03-20T05:37:58.393507Z","end":"2026-03-20T05:37:58.497434Z","steps":["trace[1065948351] 'count revisions from in-memory index tree' (duration: 103.640232ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:58.890617Z","caller":"traceutil/trace.go:171","msg":"trace[1481375331] linearizableReadLoop","detail":"{readStateIndex:2481; appliedIndex:2480; }","duration":"153.497494ms","start":"2026-03-20T05:37:58.737096Z","end":"2026-03-20T05:37:58.890593Z","steps":["trace[1481375331] 'read index received' (duration: 153.248957ms)","trace[1481375331] 'applied index is now lower than readState.Index' (duration: 247.427µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:37:58.890675Z","caller":"traceutil/trace.go:171","msg":"trace[684412375] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"247.115098ms","start":"2026-03-20T05:37:58.64353Z","end":"2026-03-20T05:37:58.890645Z","steps":["trace[684412375] 'process raft request' (duration: 246.906963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:37:58.890869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.761643ms","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-03-20T05:37:58.890912Z","caller":"traceutil/trace.go:171","msg":"trace[319864254] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2386; }","duration":"153.913126ms","start":"2026-03-20T05:37:58.736983Z","end":"2026-03-20T05:37:58.890896Z","steps":["trace[319864254] 'agreement among raft nodes before linearized reading' (duration: 153.767302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:37:58.891481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.408586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:37:58.891543Z","caller":"traceutil/trace.go:171","msg":"trace[1577344682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2386; }","duration":"137.513978ms","start":"2026-03-20T05:37:58.754015Z","end":"2026-03-20T05:37:58.891529Z","steps":["trace[1577344682] 'agreement among raft nodes before linearized reading' (duration: 136.915082ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:59.015572Z","caller":"traceutil/trace.go:171","msg":"trace[1331235320] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"119.067745ms","start":"2026-03-20T05:37:58.896452Z","end":"2026-03-20T05:37:59.01552Z","steps":["trace[1331235320] 'process raft request' (duration: 117.692279ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:59.015845Z","caller":"traceutil/trace.go:171","msg":"trace[1278522662] transaction","detail":"{read_only:false; response_revision:2388; number_of_response:1; }","duration":"117.185314ms","start":"2026-03-20T05:37:58.89858Z","end":"2026-03-20T05:37:59.015765Z","steps":["trace[1278522662] 'process raft request' (duration: 116.847885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:38:55.413723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.219014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-20T05:38:55.413863Z","caller":"traceutil/trace.go:171","msg":"trace[53308908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2909; }","duration":"105.664716ms","start":"2026-03-20T05:38:55.308172Z","end":"2026-03-20T05:38:55.413837Z","steps":["trace[53308908] 'range keys from in-memory index tree' (duration: 104.979958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:17.238679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.170066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-20T05:40:17.238759Z","caller":"traceutil/trace.go:171","msg":"trace[1533151045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4105; }","duration":"204.312019ms","start":"2026-03-20T05:40:17.034433Z","end":"2026-03-20T05:40:17.238745Z","steps":["trace[1533151045] 'range keys from in-memory index tree' (duration: 204.029531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:17.239057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.153789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:781808"} {"level":"info","ts":"2026-03-20T05:40:17.239126Z","caller":"traceutil/trace.go:171","msg":"trace[202589493] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4105; }","duration":"190.239231ms","start":"2026-03-20T05:40:17.048873Z","end":"2026-03-20T05:40:17.239113Z","steps":["trace[202589493] 'range keys from in-memory index tree' (duration: 189.725286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:19.481947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.813796ms","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-03-20T05:40:19.482067Z","caller":"traceutil/trace.go:171","msg":"trace[34824564] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4157; }","duration":"152.026321ms","start":"2026-03-20T05:40:19.330015Z","end":"2026-03-20T05:40:19.482041Z","steps":["trace[34824564] 'range keys from in-memory index tree' (duration: 151.565579ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:40:19.481965Z","caller":"traceutil/trace.go:171","msg":"trace[637150278] transaction","detail":"{read_only:false; response_revision:4158; number_of_response:1; }","duration":"120.75198ms","start":"2026-03-20T05:40:19.361183Z","end":"2026-03-20T05:40:19.481935Z","steps":["trace[637150278] 'process raft request' (duration: 106.61757ms)","trace[637150278] 'compare' (duration: 13.606756ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:40:31.067992Z","caller":"traceutil/trace.go:171","msg":"trace[813543652] transaction","detail":"{read_only:false; response_revision:4371; number_of_response:1; }","duration":"101.40651ms","start":"2026-03-20T05:40:30.966559Z","end":"2026-03-20T05:40:31.067965Z","steps":["trace[813543652] 'process raft request' (duration: 64.822195ms)","trace[813543652] 'compare' (duration: 36.224825ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:40:31.068224Z","caller":"traceutil/trace.go:171","msg":"trace[1752579481] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4371; }","duration":"101.211604ms","start":"2026-03-20T05:40:30.967001Z","end":"2026-03-20T05:40:31.068213Z","steps":["trace[1752579481] 'process raft request' (duration: 100.723311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.068358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:1717"} {"level":"info","ts":"2026-03-20T05:40:31.068399Z","caller":"traceutil/trace.go:171","msg":"trace[1174435552] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4371; }","duration":"108.202822ms","start":"2026-03-20T05:40:30.960185Z","end":"2026-03-20T05:40:31.068388Z","steps":["trace[1174435552] 'agreement among raft nodes before linearized reading' (duration: 108.12662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.068587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.287402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"} {"level":"info","ts":"2026-03-20T05:40:31.06862Z","caller":"traceutil/trace.go:171","msg":"trace[1318534906] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:4371; }","duration":"112.322883ms","start":"2026-03-20T05:40:30.956289Z","end":"2026-03-20T05:40:31.068612Z","steps":["trace[1318534906] 'agreement among raft nodes before linearized reading' (duration: 112.20093ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:40:31.495987Z","caller":"traceutil/trace.go:171","msg":"trace[1798524925] transaction","detail":"{read_only:false; response_revision:4375; number_of_response:1; }","duration":"261.380184ms","start":"2026-03-20T05:40:31.234565Z","end":"2026-03-20T05:40:31.495945Z","steps":["trace[1798524925] 'process raft request' (duration: 261.166319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.825493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.273423ms","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-20T05:40:31.825653Z","caller":"traceutil/trace.go:171","msg":"trace[1557918474] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4375; }","duration":"115.455528ms","start":"2026-03-20T05:40:31.710173Z","end":"2026-03-20T05:40:31.825629Z","steps":["trace[1557918474] 'range keys from in-memory index tree' (duration: 115.255572ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:40:31.825775Z","caller":"traceutil/trace.go:171","msg":"trace[1421688283] transaction","detail":"{read_only:false; response_revision:4376; number_of_response:1; }","duration":"540.522118ms","start":"2026-03-20T05:40:31.28517Z","end":"2026-03-20T05:40:31.825693Z","steps":["trace[1421688283] 'process raft request' (duration: 463.580447ms)","trace[1421688283] 'compare' (duration: 76.594832ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:40:31.825805Z","caller":"traceutil/trace.go:171","msg":"trace[1047777886] linearizableReadLoop","detail":"{readStateIndex:4568; appliedIndex:4566; }","duration":"530.1756ms","start":"2026-03-20T05:40:31.295535Z","end":"2026-03-20T05:40:31.82571Z","steps":["trace[1047777886] 'read index received' (duration: 200.26246ms)","trace[1047777886] 'applied index is now lower than readState.Index' (duration: 329.91171ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:40:31.825964Z","caller":"traceutil/trace.go:171","msg":"trace[860934753] transaction","detail":"{read_only:false; response_revision:4377; number_of_response:1; }","duration":"324.261667ms","start":"2026-03-20T05:40:31.501649Z","end":"2026-03-20T05:40:31.825911Z","steps":["trace[860934753] 'process raft request' (duration: 323.959169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.826212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.637862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-784xr\" ","response":"range_response_count:1 size:7813"} {"level":"info","ts":"2026-03-20T05:40:31.826342Z","caller":"traceutil/trace.go:171","msg":"trace[1610610516] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-784xr; range_end:; response_count:1; response_revision:4377; }","duration":"530.779345ms","start":"2026-03-20T05:40:31.295514Z","end":"2026-03-20T05:40:31.826293Z","steps":["trace[1610610516] 'agreement among raft nodes before linearized reading' (duration: 530.477887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.826342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.133151ms","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":"warn","ts":"2026-03-20T05:40:31.826367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.164129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-20T05:40:31.826424Z","caller":"traceutil/trace.go:171","msg":"trace[2068206034] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4377; }","duration":"205.283895ms","start":"2026-03-20T05:40:31.62112Z","end":"2026-03-20T05:40:31.826404Z","steps":["trace[2068206034] 'agreement among raft nodes before linearized reading' (duration: 205.029448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.826262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.908232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-20T05:40:31.826464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.335226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2265"} {"level":"warn","ts":"2026-03-20T05:40:31.826449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.5386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-20T05:40:31.826439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:40:31.295507Z","time spent":"530.885009ms","remote":"127.0.0.1:39248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7837,"request content":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-784xr\" "} {"level":"info","ts":"2026-03-20T05:40:31.826532Z","caller":"traceutil/trace.go:171","msg":"trace[1353784587] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:4377; }","duration":"322.441209ms","start":"2026-03-20T05:40:31.504073Z","end":"2026-03-20T05:40:31.826514Z","steps":["trace[1353784587] 'agreement among raft nodes before linearized reading' (duration: 322.126591ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:40:31.826537Z","caller":"traceutil/trace.go:171","msg":"trace[1231517997] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:4377; }","duration":"219.191479ms","start":"2026-03-20T05:40:31.607284Z","end":"2026-03-20T05:40:31.826476Z","steps":["trace[1231517997] 'agreement among raft nodes before linearized reading' (duration: 218.85429ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.826507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.774174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-20T05:40:31.82693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:40:31.501631Z","time spent":"324.414252ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:40:31.827015Z","caller":"traceutil/trace.go:171","msg":"trace[844808664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4377; }","duration":"149.32301ms","start":"2026-03-20T05:40:31.677628Z","end":"2026-03-20T05:40:31.826951Z","steps":["trace[844808664] 'agreement among raft nodes before linearized reading' (duration: 148.528448ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:40:31.826561Z","caller":"traceutil/trace.go:171","msg":"trace[1611085625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4377; }","duration":"462.693434ms","start":"2026-03-20T05:40:31.36384Z","end":"2026-03-20T05:40:31.826533Z","steps":["trace[1611085625] 'agreement among raft nodes before linearized reading' (duration: 462.306553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.827144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:40:31.363829Z","time spent":"463.299661ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T05:40:31.826432Z","caller":"traceutil/trace.go:171","msg":"trace[1908334661] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4377; }","duration":"326.292723ms","start":"2026-03-20T05:40:31.500121Z","end":"2026-03-20T05:40:31.826414Z","steps":["trace[1908334661] 'agreement among raft nodes before linearized reading' (duration: 326.045946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.826594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:40:31.504055Z","time spent":"322.528941ms","remote":"127.0.0.1:50000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":2289,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-03-20T05:40:31.827354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:40:31.285155Z","time spent":"540.781196ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:40:31.827342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:40:31.500089Z","time spent":"327.230028ms","remote":"127.0.0.1:39406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-03-20T05:40:31.826605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.380082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-20T05:40:31.827664Z","caller":"traceutil/trace.go:171","msg":"trace[824720676] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4377; }","duration":"328.484031ms","start":"2026-03-20T05:40:31.499165Z","end":"2026-03-20T05:40:31.827649Z","steps":["trace[824720676] 'agreement among raft nodes before linearized reading' (duration: 327.016341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:40:31.827812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:40:31.499137Z","time spent":"328.605525ms","remote":"127.0.0.1:39406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1148,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-03-20T05:41:18.864257Z","caller":"traceutil/trace.go:171","msg":"trace[212450235] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"100.436142ms","start":"2026-03-20T05:41:18.763789Z","end":"2026-03-20T05:41:18.864225Z","steps":["trace[212450235] 'process raft request' (duration: 85.372889ms)","trace[212450235] 'compare' (duration: 14.848358ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:44:11.443668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-03-20T05:44:11.522588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"76.84214ms","hash":4283303023,"current-db-size-bytes":43806720,"current-db-size":"44 MB","current-db-size-in-use-bytes":30240768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-20T05:44:11.522685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4283303023,"revision":3014,"compact-revision":-1} {"level":"info","ts":"2026-03-20T05:47:10.65649Z","caller":"traceutil/trace.go:171","msg":"trace[1372700136] transaction","detail":"{read_only:false; response_revision:7786; number_of_response:1; }","duration":"151.835925ms","start":"2026-03-20T05:47:10.504612Z","end":"2026-03-20T05:47:10.656448Z","steps":["trace[1372700136] 'process raft request' (duration: 151.566788ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:10.65639Z","caller":"traceutil/trace.go:171","msg":"trace[731490399] linearizableReadLoop","detail":"{readStateIndex:8150; appliedIndex:8149; }","duration":"150.688533ms","start":"2026-03-20T05:47:10.505672Z","end":"2026-03-20T05:47:10.656361Z","steps":["trace[731490399] 'read index received' (duration: 150.540559ms)","trace[731490399] 'applied index is now lower than readState.Index' (duration: 146.564µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:47:10.65699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.28767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:203506"} {"level":"info","ts":"2026-03-20T05:47:10.65709Z","caller":"traceutil/trace.go:171","msg":"trace[902430927] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7786; }","duration":"151.422794ms","start":"2026-03-20T05:47:10.505654Z","end":"2026-03-20T05:47:10.657077Z","steps":["trace[902430927] 'agreement among raft nodes before linearized reading' (duration: 150.884898ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:10.917983Z","caller":"traceutil/trace.go:171","msg":"trace[2136207371] linearizableReadLoop","detail":"{readStateIndex:8151; appliedIndex:8150; }","duration":"258.42094ms","start":"2026-03-20T05:47:10.659523Z","end":"2026-03-20T05:47:10.917944Z","steps":["trace[2136207371] 'read index received' (duration: 236.424806ms)","trace[2136207371] 'applied index is now lower than readState.Index' (duration: 21.994264ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:47:10.918048Z","caller":"traceutil/trace.go:171","msg":"trace[82622616] transaction","detail":"{read_only:false; response_revision:7787; number_of_response:1; }","duration":"372.774743ms","start":"2026-03-20T05:47:10.545236Z","end":"2026-03-20T05:47:10.918011Z","steps":["trace[82622616] 'process raft request' (duration: 350.706958ms)","trace[82622616] 'compare' (duration: 21.824359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:47:10.918229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:47:10.545201Z","time spent":"372.927118ms","remote":"127.0.0.1:39322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:47:10.918248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.702238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-03-20T05:47:10.918327Z","caller":"traceutil/trace.go:171","msg":"trace[336498555] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7787; }","duration":"258.862101ms","start":"2026-03-20T05:47:10.659446Z","end":"2026-03-20T05:47:10.918308Z","steps":["trace[336498555] 'agreement among raft nodes before linearized reading' (duration: 258.628495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:47:10.918342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.599143ms","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-20T05:47:10.918394Z","caller":"traceutil/trace.go:171","msg":"trace[1522902572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7787; }","duration":"235.711516ms","start":"2026-03-20T05:47:10.682669Z","end":"2026-03-20T05:47:10.91838Z","steps":["trace[1522902572] 'agreement among raft nodes before linearized reading' (duration: 235.501381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:47:10.918382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.902844ms","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-20T05:47:10.918458Z","caller":"traceutil/trace.go:171","msg":"trace[463403361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7787; }","duration":"229.041197ms","start":"2026-03-20T05:47:10.689398Z","end":"2026-03-20T05:47:10.918439Z","steps":["trace[463403361] 'agreement among raft nodes before linearized reading' (duration: 228.858482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:47:10.918449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.859101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-03-20T05:47:10.918546Z","caller":"traceutil/trace.go:171","msg":"trace[1093520791] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:7787; }","duration":"192.976384ms","start":"2026-03-20T05:47:10.72555Z","end":"2026-03-20T05:47:10.918526Z","steps":["trace[1093520791] 'agreement among raft nodes before linearized reading' (duration: 192.687406ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:11.495334Z","caller":"traceutil/trace.go:171","msg":"trace[2010760882] linearizableReadLoop","detail":"{readStateIndex:8156; appliedIndex:8155; }","duration":"135.866425ms","start":"2026-03-20T05:47:11.359437Z","end":"2026-03-20T05:47:11.495304Z","steps":["trace[2010760882] 'read index received' (duration: 135.707401ms)","trace[2010760882] 'applied index is now lower than readState.Index' (duration: 157.504µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:47:11.495424Z","caller":"traceutil/trace.go:171","msg":"trace[59238432] transaction","detail":"{read_only:false; response_revision:7792; number_of_response:1; }","duration":"256.745774ms","start":"2026-03-20T05:47:11.238645Z","end":"2026-03-20T05:47:11.495391Z","steps":["trace[59238432] 'process raft request' (duration: 256.457487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:47:11.495545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.08896ms","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-03-20T05:47:11.495585Z","caller":"traceutil/trace.go:171","msg":"trace[1451009048] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7792; }","duration":"136.197623ms","start":"2026-03-20T05:47:11.359373Z","end":"2026-03-20T05:47:11.495571Z","steps":["trace[1451009048] 'agreement among raft nodes before linearized reading' (duration: 136.033319ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:11.591401Z","caller":"traceutil/trace.go:171","msg":"trace[1293798919] transaction","detail":"{read_only:false; response_revision:7793; number_of_response:1; }","duration":"105.789893ms","start":"2026-03-20T05:47:11.485565Z","end":"2026-03-20T05:47:11.591355Z","steps":["trace[1293798919] 'process raft request' (duration: 72.236248ms)","trace[1293798919] 'compare' (duration: 33.265387ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:47:38.174048Z","caller":"traceutil/trace.go:171","msg":"trace[194931921] linearizableReadLoop","detail":"{readStateIndex:8386; appliedIndex:8385; }","duration":"219.514439ms","start":"2026-03-20T05:47:37.954504Z","end":"2026-03-20T05:47:38.174019Z","steps":["trace[194931921] 'read index received' (duration: 219.385606ms)","trace[194931921] 'applied index is now lower than readState.Index' (duration: 127.683µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:47:38.174174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.652693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:47:38.174203Z","caller":"traceutil/trace.go:171","msg":"trace[820143202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8009; }","duration":"219.765996ms","start":"2026-03-20T05:47:37.954428Z","end":"2026-03-20T05:47:38.174194Z","steps":["trace[820143202] 'agreement among raft nodes before linearized reading' (duration: 219.690614ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:38.174218Z","caller":"traceutil/trace.go:171","msg":"trace[1884683203] transaction","detail":"{read_only:false; response_revision:8009; number_of_response:1; }","duration":"266.99681ms","start":"2026-03-20T05:47:37.90716Z","end":"2026-03-20T05:47:38.174157Z","steps":["trace[1884683203] 'process raft request' (duration: 266.709332ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:38.319688Z","caller":"traceutil/trace.go:171","msg":"trace[1250342503] transaction","detail":"{read_only:false; response_revision:8010; number_of_response:1; }","duration":"137.018445ms","start":"2026-03-20T05:47:38.182612Z","end":"2026-03-20T05:47:38.319631Z","steps":["trace[1250342503] 'process raft request' (duration: 84.071807ms)","trace[1250342503] 'compare' (duration: 52.482216ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:47:52.620582Z","caller":"traceutil/trace.go:171","msg":"trace[392493559] linearizableReadLoop","detail":"{readStateIndex:8525; appliedIndex:8524; }","duration":"198.665918ms","start":"2026-03-20T05:47:52.42189Z","end":"2026-03-20T05:47:52.620556Z","steps":["trace[392493559] 'read index received' (duration: 198.560505ms)","trace[392493559] 'applied index is now lower than readState.Index' (duration: 104.133µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:47:52.620703Z","caller":"traceutil/trace.go:171","msg":"trace[1080682266] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"204.539866ms","start":"2026-03-20T05:47:52.416115Z","end":"2026-03-20T05:47:52.620655Z","steps":["trace[1080682266] 'process raft request' (duration: 204.269449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:47:52.620839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.925516ms","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-20T05:47:52.620893Z","caller":"traceutil/trace.go:171","msg":"trace[261150009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8140; }","duration":"199.020707ms","start":"2026-03-20T05:47:52.421857Z","end":"2026-03-20T05:47:52.620877Z","steps":["trace[261150009] 'agreement among raft nodes before linearized reading' (duration: 198.834052ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:52.68958Z","caller":"traceutil/trace.go:171","msg":"trace[346867895] transaction","detail":"{read_only:false; response_revision:8141; number_of_response:1; }","duration":"189.965994ms","start":"2026-03-20T05:47:52.499575Z","end":"2026-03-20T05:47:52.689541Z","steps":["trace[346867895] 'process raft request' (duration: 189.733437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:47:52.689679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.964537ms","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":"warn","ts":"2026-03-20T05:47:52.689671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.490367ms","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-20T05:47:52.689751Z","caller":"traceutil/trace.go:171","msg":"trace[1990484793] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8141; }","duration":"235.037469ms","start":"2026-03-20T05:47:52.454694Z","end":"2026-03-20T05:47:52.689731Z","steps":["trace[1990484793] 'agreement among raft nodes before linearized reading' (duration: 234.862904ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:52.689783Z","caller":"traceutil/trace.go:171","msg":"trace[861782942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8141; }","duration":"263.684981ms","start":"2026-03-20T05:47:52.42607Z","end":"2026-03-20T05:47:52.689755Z","steps":["trace[861782942] 'agreement among raft nodes before linearized reading' (duration: 263.404214ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:47:52.739365Z","caller":"traceutil/trace.go:171","msg":"trace[91742268] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"113.161701ms","start":"2026-03-20T05:47:52.626149Z","end":"2026-03-20T05:47:52.739311Z","steps":["trace[91742268] 'process raft request' (duration: 74.428967ms)","trace[91742268] 'compare' (duration: 38.463767ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:48:57.197342Z","caller":"traceutil/trace.go:171","msg":"trace[2089119827] linearizableReadLoop","detail":"{readStateIndex:9237; appliedIndex:9236; }","duration":"110.82492ms","start":"2026-03-20T05:48:57.08648Z","end":"2026-03-20T05:48:57.197305Z","steps":["trace[2089119827] 'read index received' (duration: 110.534663ms)","trace[2089119827] 'applied index is now lower than readState.Index' (duration: 288.447µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:48:57.197397Z","caller":"traceutil/trace.go:171","msg":"trace[716709168] transaction","detail":"{read_only:false; response_revision:8820; number_of_response:1; }","duration":"110.993505ms","start":"2026-03-20T05:48:57.086367Z","end":"2026-03-20T05:48:57.197361Z","steps":["trace[716709168] 'process raft request' (duration: 110.700267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:48:57.197696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.178089ms","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-20T05:48:57.197796Z","caller":"traceutil/trace.go:171","msg":"trace[1139572228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8820; }","duration":"111.368195ms","start":"2026-03-20T05:48:57.086405Z","end":"2026-03-20T05:48:57.197773Z","steps":["trace[1139572228] 'agreement among raft nodes before linearized reading' (duration: 111.020845ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:49:11.453129Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6177} {"level":"info","ts":"2026-03-20T05:49:11.556206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6177,"took":"100.554332ms","hash":2198606740,"current-db-size-bytes":47628288,"current-db-size":"48 MB","current-db-size-in-use-bytes":23527424,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-20T05:49:11.556264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2198606740,"revision":6177,"compact-revision":3014} {"level":"info","ts":"2026-03-20T05:50:22.122034Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e10bb51ed7578852","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-20T05:50:22.135498Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-20T05:50:22.135611Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-20T05:54:11.461004Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8953} {"level":"info","ts":"2026-03-20T05:54:11.552074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8953,"took":"87.803916ms","hash":2043520277,"current-db-size-bytes":47628288,"current-db-size":"48 MB","current-db-size-in-use-bytes":21565440,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-20T05:54:11.552172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2043520277,"revision":8953,"compact-revision":6177} {"level":"info","ts":"2026-03-20T05:59:11.468084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11158} {"level":"info","ts":"2026-03-20T05:59:11.548182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11158,"took":"77.724692ms","hash":1070256756,"current-db-size-bytes":47628288,"current-db-size":"48 MB","current-db-size-in-use-bytes":19349504,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-03-20T05:59:11.549062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1070256756,"revision":11158,"compact-revision":8953} {"level":"info","ts":"2026-03-20T06:00:02.79216Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000369a.wal"}