{"level":"warn","ts":"2026-04-17T05:54:50.144955Z","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-17T05:54:50.145318Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-17T05:54:50.145401Z","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-17T05:54:50.145416Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]}
{"level":"info","ts":"2026-04-17T05:54:50.14544Z","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-17T05:54:50.146253Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]}
{"level":"info","ts":"2026-04-17T05:54:50.146357Z","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.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-17T05:54:50.150324Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.705573ms"}
{"level":"info","ts":"2026-04-17T05:54:50.156779Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"}
{"level":"info","ts":"2026-04-17T05:54:50.157066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"}
{"level":"info","ts":"2026-04-17T05:54:50.157117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"}
{"level":"info","ts":"2026-04-17T05:54:50.157211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-17T05:54:50.157296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"}
{"level":"info","ts":"2026-04-17T05:54:50.157423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"}
{"level":"warn","ts":"2026-04-17T05:54:50.162555Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-17T05:54:50.164445Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-17T05:54:50.166042Z","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-17T05:54:50.168199Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-17T05:54:50.168858Z","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-17T05:54:50.168971Z","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-17T05:54:50.168986Z","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-17T05:54:50.168902Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-17T05:54:50.1716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"}
{"level":"info","ts":"2026-04-17T05:54:50.171937Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]}
{"level":"info","ts":"2026-04-17T05:54:50.173537Z","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-17T05:54:50.173636Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"}
{"level":"info","ts":"2026-04-17T05:54:50.173664Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"}
{"level":"info","ts":"2026-04-17T05:54:50.173952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-17T05:54:50.174026Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-17T05:54:50.357794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"}
{"level":"info","ts":"2026-04-17T05:54:50.357889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-17T05:54:50.357931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"}
{"level":"info","ts":"2026-04-17T05:54:50.357955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"}
{"level":"info","ts":"2026-04-17T05:54:50.357968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"}
{"level":"info","ts":"2026-04-17T05:54:50.357985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"}
{"level":"info","ts":"2026-04-17T05:54:50.358006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"}
{"level":"info","ts":"2026-04-17T05:54:50.358874Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-17T05:54:50.358893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-17T05:54:50.358964Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T05:54:50.358978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-17T05:54:50.359263Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-17T05:54:50.359393Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-17T05:54:50.360693Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T05:54:50.360835Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T05:54:50.360885Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-17T05:54:50.36147Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-17T05:54:50.362786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"}
{"level":"info","ts":"2026-04-17T05:55:28.430627Z","caller":"traceutil/trace.go:171","msg":"trace[1791654928] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"328.086225ms","start":"2026-04-17T05:55:28.102496Z","end":"2026-04-17T05:55:28.430583Z","steps":["trace[1791654928] 'read index received'  (duration: 305.401971ms)","trace[1791654928] 'applied index is now lower than readState.Index'  (duration: 22.682794ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T05:55:28.430753Z","caller":"traceutil/trace.go:171","msg":"trace[1320332436] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"458.953156ms","start":"2026-04-17T05:55:27.971759Z","end":"2026-04-17T05:55:28.430712Z","steps":["trace[1320332436] 'process raft request'  (duration: 436.134807ms)","trace[1320332436] 'compare'  (duration: 22.4902ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T05:55:28.431236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.88719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"warn","ts":"2026-04-17T05:55:28.431205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.006953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"}
{"level":"warn","ts":"2026-04-17T05:55:28.431342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.816426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"}
{"level":"warn","ts":"2026-04-17T05:55:28.431345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:27.971734Z","time spent":"459.105949ms","remote":"127.0.0.1:51880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" mod_revision:0 > success:<request_put:<key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" value_size:1527 >> failure:<>"}
{"level":"info","ts":"2026-04-17T05:55:28.43144Z","caller":"traceutil/trace.go:171","msg":"trace[1719696309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"328.27705ms","start":"2026-04-17T05:55:28.10311Z","end":"2026-04-17T05:55:28.431387Z","steps":["trace[1719696309] 'agreement among raft nodes before linearized reading'  (duration: 327.849858ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T05:55:28.431461Z","caller":"traceutil/trace.go:171","msg":"trace[1846700384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"329.00074ms","start":"2026-04-17T05:55:28.102428Z","end":"2026-04-17T05:55:28.431429Z","steps":["trace[1846700384] 'agreement among raft nodes before linearized reading'  (duration: 328.61923ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T05:55:28.431509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:28.103082Z","time spent":"328.413034ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-17T05:55:28.431517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:28.102403Z","time spent":"329.104804ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-04-17T05:55:28.431357Z","caller":"traceutil/trace.go:171","msg":"trace[1504882385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"328.085856ms","start":"2026-04-17T05:55:28.103249Z","end":"2026-04-17T05:55:28.431334Z","steps":["trace[1504882385] 'agreement among raft nodes before linearized reading'  (duration: 327.792028ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T05:55:28.431662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:55:28.103243Z","time spent":"328.397694ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-17T05:56:32.968101Z","caller":"traceutil/trace.go:171","msg":"trace[558344720] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"160.170331ms","start":"2026-04-17T05:56:32.807867Z","end":"2026-04-17T05:56:32.968037Z","steps":["trace[558344720] 'process raft request'  (duration: 135.687612ms)","trace[558344720] 'compare'  (duration: 24.293824ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T05:56:32.968228Z","caller":"traceutil/trace.go:171","msg":"trace[47232026] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"159.562283ms","start":"2026-04-17T05:56:32.808628Z","end":"2026-04-17T05:56:32.96819Z","steps":["trace[47232026] 'process raft request'  (duration: 159.365668ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T05:56:54.516678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.269053ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5646842792570043095 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:1701 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-17T05:56:54.516859Z","caller":"traceutil/trace.go:171","msg":"trace[1315516120] linearizableReadLoop","detail":"{readStateIndex:1835; appliedIndex:1834; }","duration":"369.796464ms","start":"2026-04-17T05:56:54.147037Z","end":"2026-04-17T05:56:54.516833Z","steps":["trace[1315516120] 'read index received'  (duration: 9.815311ms)","trace[1315516120] 'applied index is now lower than readState.Index'  (duration: 359.979233ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T05:56:54.517058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.90649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-17T05:56:54.517133Z","caller":"traceutil/trace.go:171","msg":"trace[1683421195] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1778; }","duration":"195.033103ms","start":"2026-04-17T05:56:54.322085Z","end":"2026-04-17T05:56:54.517118Z","steps":["trace[1683421195] 'agreement among raft nodes before linearized reading'  (duration: 194.886459ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T05:56:54.517064Z","caller":"traceutil/trace.go:171","msg":"trace[692240432] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"472.105483ms","start":"2026-04-17T05:56:54.044912Z","end":"2026-04-17T05:56:54.517018Z","steps":["trace[692240432] 'process raft request'  (duration: 112.044848ms)","trace[692240432] 'compare'  (duration: 358.999115ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T05:56:54.51786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.778701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-04-17T05:56:54.518241Z","caller":"traceutil/trace.go:171","msg":"trace[75286612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1778; }","duration":"371.240354ms","start":"2026-04-17T05:56:54.146967Z","end":"2026-04-17T05:56:54.518207Z","steps":["trace[75286612] 'agreement among raft nodes before linearized reading'  (duration: 370.601926ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T05:56:54.5185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:54.146942Z","time spent":"371.360117ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-04-17T05:56:54.518205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T05:56:54.044876Z","time spent":"473.164682ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:1701 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-17T05:56:54.657034Z","caller":"traceutil/trace.go:171","msg":"trace[528531778] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"131.6353ms","start":"2026-04-17T05:56:54.525376Z","end":"2026-04-17T05:56:54.657012Z","steps":["trace[528531778] 'process raft request'  (duration: 131.552798ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T05:56:54.657072Z","caller":"traceutil/trace.go:171","msg":"trace[851005220] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"132.03742ms","start":"2026-04-17T05:56:54.524992Z","end":"2026-04-17T05:56:54.65703Z","steps":["trace[851005220] 'process raft request'  (duration: 107.393309ms)","trace[851005220] 'compare'  (duration: 24.402375ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T05:57:37.346144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.705659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:551"}
{"level":"info","ts":"2026-04-17T05:57:37.345897Z","caller":"traceutil/trace.go:171","msg":"trace[370163903] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"150.726237ms","start":"2026-04-17T05:57:37.195123Z","end":"2026-04-17T05:57:37.34585Z","steps":["trace[370163903] 'process raft request'  (duration: 150.520652ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T05:57:37.347686Z","caller":"traceutil/trace.go:171","msg":"trace[1435492955] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2121; }","duration":"134.303284ms","start":"2026-04-17T05:57:37.213361Z","end":"2026-04-17T05:57:37.347665Z","steps":["trace[1435492955] 'agreement among raft nodes before linearized reading'  (duration: 132.650728ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T05:57:37.345726Z","caller":"traceutil/trace.go:171","msg":"trace[994833979] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2193; }","duration":"132.288637ms","start":"2026-04-17T05:57:37.21341Z","end":"2026-04-17T05:57:37.345698Z","steps":["trace[994833979] 'read index received'  (duration: 125.15403ms)","trace[994833979] 'applied index is now lower than readState.Index'  (duration: 7.133727ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T05:57:37.345834Z","caller":"traceutil/trace.go:171","msg":"trace[541854769] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"154.343347ms","start":"2026-04-17T05:57:37.191428Z","end":"2026-04-17T05:57:37.345771Z","steps":["trace[541854769] 'process raft request'  (duration: 147.080086ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T05:57:37.656308Z","caller":"traceutil/trace.go:171","msg":"trace[686500379] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"211.883299ms","start":"2026-04-17T05:57:37.444379Z","end":"2026-04-17T05:57:37.656262Z","steps":["trace[686500379] 'process raft request'  (duration: 175.134653ms)","trace[686500379] 'compare'  (duration: 36.368355ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T05:58:50.143778Z","caller":"traceutil/trace.go:171","msg":"trace[1916979547] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"219.569706ms","start":"2026-04-17T05:58:49.924183Z","end":"2026-04-17T05:58:50.143753Z","steps":["trace[1916979547] 'process raft request'  (duration: 219.374681ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T05:59:52.076464Z","caller":"traceutil/trace.go:171","msg":"trace[1459139275] transaction","detail":"{read_only:false; response_revision:3261; number_of_response:1; }","duration":"149.736448ms","start":"2026-04-17T05:59:51.926697Z","end":"2026-04-17T05:59:52.076433Z","steps":["trace[1459139275] 'process raft request'  (duration: 127.322918ms)","trace[1459139275] 'compare'  (duration: 22.296166ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T05:59:52.122827Z","caller":"traceutil/trace.go:171","msg":"trace[1380238986] transaction","detail":"{read_only:false; response_revision:3262; number_of_response:1; }","duration":"165.796961ms","start":"2026-04-17T05:59:51.956998Z","end":"2026-04-17T05:59:52.122795Z","steps":["trace[1380238986] 'process raft request'  (duration: 165.498993ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:09.293437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.989659ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5646842792570049800 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" mod_revision:0 > success:<request_put:<key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" value_size:7315 >> failure:<>>","response":"size:5"}
{"level":"info","ts":"2026-04-17T06:01:09.293581Z","caller":"traceutil/trace.go:171","msg":"trace[1401702424] linearizableReadLoop","detail":"{readStateIndex:4664; appliedIndex:4663; }","duration":"240.47684ms","start":"2026-04-17T06:01:09.05308Z","end":"2026-04-17T06:01:09.293557Z","steps":["trace[1401702424] 'read index received'  (duration: 60.216683ms)","trace[1401702424] 'applied index is now lower than readState.Index'  (duration: 180.257757ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T06:01:09.293697Z","caller":"traceutil/trace.go:171","msg":"trace[1635312012] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4470; }","duration":"272.530754ms","start":"2026-04-17T06:01:09.021156Z","end":"2026-04-17T06:01:09.293686Z","steps":["trace[1635312012] 'process raft request'  (duration: 92.201556ms)","trace[1635312012] 'compare'  (duration: 179.922647ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T06:01:09.294122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.873778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-04-17T06:01:09.294177Z","caller":"traceutil/trace.go:171","msg":"trace[204783760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4470; }","duration":"216.98861ms","start":"2026-04-17T06:01:09.077176Z","end":"2026-04-17T06:01:09.294164Z","steps":["trace[204783760] 'agreement among raft nodes before linearized reading'  (duration: 216.808296ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:09.29421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.472149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d050781e-3079-4ac9-bb6e-a937d51ff1da\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-04-17T06:01:09.294313Z","caller":"traceutil/trace.go:171","msg":"trace[1227272618] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d050781e-3079-4ac9-bb6e-a937d51ff1da; range_end:; response_count:1; response_revision:4470; }","duration":"140.648244ms","start":"2026-04-17T06:01:09.153642Z","end":"2026-04-17T06:01:09.29429Z","steps":["trace[1227272618] 'agreement among raft nodes before linearized reading'  (duration: 140.374985ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:09.294452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.316401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-17T06:01:09.294477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.357985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T06:01:09.294559Z","caller":"traceutil/trace.go:171","msg":"trace[826552193] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4470; }","duration":"179.499276ms","start":"2026-04-17T06:01:09.115036Z","end":"2026-04-17T06:01:09.294535Z","steps":["trace[826552193] 'agreement among raft nodes before linearized reading'  (duration: 179.2912ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:09.29458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.59098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"}
{"level":"info","ts":"2026-04-17T06:01:09.294635Z","caller":"traceutil/trace.go:171","msg":"trace[1729427489] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4470; }","duration":"166.695043ms","start":"2026-04-17T06:01:09.127927Z","end":"2026-04-17T06:01:09.294622Z","steps":["trace[1729427489] 'agreement among raft nodes before linearized reading'  (duration: 166.417355ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:09.294604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.966965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:781824"}
{"level":"info","ts":"2026-04-17T06:01:09.294751Z","caller":"traceutil/trace.go:171","msg":"trace[545510754] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4470; }","duration":"161.121329ms","start":"2026-04-17T06:01:09.133616Z","end":"2026-04-17T06:01:09.294737Z","steps":["trace[545510754] 'agreement among raft nodes before linearized reading'  (duration: 160.702248ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:01:09.29457Z","caller":"traceutil/trace.go:171","msg":"trace[1986910885] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4470; }","duration":"241.505839ms","start":"2026-04-17T06:01:09.053041Z","end":"2026-04-17T06:01:09.294546Z","steps":["trace[1986910885] 'agreement among raft nodes before linearized reading'  (duration: 241.352775ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:01:22.37821Z","caller":"traceutil/trace.go:171","msg":"trace[1517139135] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"106.276054ms","start":"2026-04-17T06:01:22.271882Z","end":"2026-04-17T06:01:22.378158Z","steps":["trace[1517139135] 'process raft request'  (duration: 58.612718ms)","trace[1517139135] 'compare'  (duration: 47.465421ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T06:01:54.580764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.168621ms","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-17T06:01:54.580913Z","caller":"traceutil/trace.go:171","msg":"trace[1866980942] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5090; }","duration":"303.360105ms","start":"2026-04-17T06:01:54.277524Z","end":"2026-04-17T06:01:54.580884Z","steps":["trace[1866980942] 'range keys from in-memory index tree'  (duration: 302.941754ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:54.580964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:54.277516Z","time spent":"303.436397ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-17T06:01:54.580869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.130116ms","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-17T06:01:54.581169Z","caller":"traceutil/trace.go:171","msg":"trace[300949207] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5090; }","duration":"304.509277ms","start":"2026-04-17T06:01:54.276628Z","end":"2026-04-17T06:01:54.581137Z","steps":["trace[300949207] 'range keys from in-memory index tree'  (duration: 303.844229ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:54.581256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:01:54.276607Z","time spent":"304.63309ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-04-17T06:01:54.830226Z","caller":"traceutil/trace.go:171","msg":"trace[168867769] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"239.252245ms","start":"2026-04-17T06:01:54.590948Z","end":"2026-04-17T06:01:54.8302Z","steps":["trace[168867769] 'process raft request'  (duration: 239.06017ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:01:54.847687Z","caller":"traceutil/trace.go:171","msg":"trace[702196461] linearizableReadLoop","detail":"{readStateIndex:5310; appliedIndex:5308; }","duration":"251.169595ms","start":"2026-04-17T06:01:54.59649Z","end":"2026-04-17T06:01:54.847659Z","steps":["trace[702196461] 'read index received'  (duration: 233.491916ms)","trace[702196461] 'applied index is now lower than readState.Index'  (duration: 17.676509ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T06:01:54.847808Z","caller":"traceutil/trace.go:171","msg":"trace[1433286253] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"256.79619ms","start":"2026-04-17T06:01:54.590989Z","end":"2026-04-17T06:01:54.847785Z","steps":["trace[1433286253] 'process raft request'  (duration: 256.536072ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:54.847932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.413301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"}
{"level":"info","ts":"2026-04-17T06:01:54.847992Z","caller":"traceutil/trace.go:171","msg":"trace[2054037526] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5092; }","duration":"251.499434ms","start":"2026-04-17T06:01:54.596473Z","end":"2026-04-17T06:01:54.847972Z","steps":["trace[2054037526] 'agreement among raft nodes before linearized reading'  (duration: 251.32609ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:01:54.848007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.459185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T06:01:54.848147Z","caller":"traceutil/trace.go:171","msg":"trace[1370802572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5092; }","duration":"137.62293ms","start":"2026-04-17T06:01:54.710469Z","end":"2026-04-17T06:01:54.848092Z","steps":["trace[1370802572] 'agreement among raft nodes before linearized reading'  (duration: 137.446815ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:02:39.593547Z","caller":"traceutil/trace.go:171","msg":"trace[319676574] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"129.779441ms","start":"2026-04-17T06:02:39.46367Z","end":"2026-04-17T06:02:39.593449Z","steps":["trace[319676574] 'process raft request'  (duration: 128.964239ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:04:50.418398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3250}
{"level":"info","ts":"2026-04-17T06:04:50.515122Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3250,"took":"94.536638ms","hash":1888632367,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":33546240,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-04-17T06:04:50.515229Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1888632367,"revision":3250,"compact-revision":-1}
{"level":"info","ts":"2026-04-17T06:07:41.714031Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000218c.wal"}
{"level":"info","ts":"2026-04-17T06:07:47.085198Z","caller":"traceutil/trace.go:171","msg":"trace[1855991086] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"252.927414ms","start":"2026-04-17T06:07:46.832247Z","end":"2026-04-17T06:07:47.085174Z","steps":["trace[1855991086] 'process raft request'  (duration: 252.7916ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:07:47.212405Z","caller":"traceutil/trace.go:171","msg":"trace[1780554759] linearizableReadLoop","detail":"{readStateIndex:8628; appliedIndex:8626; }","duration":"365.787827ms","start":"2026-04-17T06:07:46.846585Z","end":"2026-04-17T06:07:47.212372Z","steps":["trace[1780554759] 'read index received'  (duration: 238.513127ms)","trace[1780554759] 'applied index is now lower than readState.Index'  (duration: 127.27318ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T06:07:47.212439Z","caller":"traceutil/trace.go:171","msg":"trace[1525833483] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"379.133375ms","start":"2026-04-17T06:07:46.833274Z","end":"2026-04-17T06:07:47.212407Z","steps":["trace[1525833483] 'process raft request'  (duration: 288.132265ms)","trace[1525833483] 'compare'  (duration: 90.816065ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T06:07:47.212592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:07:46.833262Z","time spent":"379.245278ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:8245 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-04-17T06:07:47.212705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.611001ms","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-17T06:07:47.212746Z","caller":"traceutil/trace.go:171","msg":"trace[1778476916] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8258; }","duration":"357.723575ms","start":"2026-04-17T06:07:46.855008Z","end":"2026-04-17T06:07:47.212732Z","steps":["trace[1778476916] 'agreement among raft nodes before linearized reading'  (duration: 357.54142ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:07:47.212783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:07:46.854977Z","time spent":"357.797626ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-17T06:07:47.212986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.367923ms","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-17T06:07:47.213077Z","caller":"traceutil/trace.go:171","msg":"trace[333737944] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8258; }","duration":"366.485096ms","start":"2026-04-17T06:07:46.846571Z","end":"2026-04-17T06:07:47.213056Z","steps":["trace[333737944] 'agreement among raft nodes before linearized reading'  (duration: 365.928291ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:07:47.213128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:07:46.846565Z","time spent":"366.554767ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "}
{"level":"warn","ts":"2026-04-17T06:07:57.699898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.979864ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5646842792570060184 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:8314 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-17T06:07:57.700274Z","caller":"traceutil/trace.go:171","msg":"trace[1567977355] transaction","detail":"{read_only:false; response_revision:8326; number_of_response:1; }","duration":"341.495586ms","start":"2026-04-17T06:07:57.358722Z","end":"2026-04-17T06:07:57.700218Z","steps":["trace[1567977355] 'process raft request'  (duration: 341.288781ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:07:57.700335Z","caller":"traceutil/trace.go:171","msg":"trace[21486138] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"344.16768ms","start":"2026-04-17T06:07:57.356111Z","end":"2026-04-17T06:07:57.700279Z","steps":["trace[21486138] 'process raft request'  (duration: 36.720782ms)","trace[21486138] 'compare'  (duration: 306.765268ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T06:07:57.700433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:07:57.358715Z","time spent":"341.63063ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:8315 > success:<request_put:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" value_size:549 >> failure:<request_range:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-17T06:07:57.700567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T06:07:57.356026Z","time spent":"344.405596ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:8314 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-17T06:07:58.342433Z","caller":"traceutil/trace.go:171","msg":"trace[896070566] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"103.904425ms","start":"2026-04-17T06:07:58.2385Z","end":"2026-04-17T06:07:58.342405Z","steps":["trace[896070566] 'process raft request'  (duration: 103.692119ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:07:58.541211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.800925ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5646842792570060200 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:8321 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-17T06:07:58.541387Z","caller":"traceutil/trace.go:171","msg":"trace[1657409418] linearizableReadLoop","detail":"{readStateIndex:8707; appliedIndex:8706; }","duration":"104.396509ms","start":"2026-04-17T06:07:58.436966Z","end":"2026-04-17T06:07:58.541363Z","steps":["trace[1657409418] 'read index received'  (duration: 1.233754ms)","trace[1657409418] 'applied index is now lower than readState.Index'  (duration: 103.161255ms)"],"step_count":2}
{"level":"info","ts":"2026-04-17T06:07:58.541431Z","caller":"traceutil/trace.go:171","msg":"trace[955409802] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"245.15162ms","start":"2026-04-17T06:07:58.29623Z","end":"2026-04-17T06:07:58.541382Z","steps":["trace[955409802] 'process raft request'  (duration: 142.024566ms)","trace[955409802] 'compare'  (duration: 102.468856ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T06:07:58.541494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.530072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T06:07:58.541541Z","caller":"traceutil/trace.go:171","msg":"trace[230164650] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8333; }","duration":"104.623705ms","start":"2026-04-17T06:07:58.436904Z","end":"2026-04-17T06:07:58.541528Z","steps":["trace[230164650] 'agreement among raft nodes before linearized reading'  (duration: 104.523162ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:08:39.870043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.199323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T06:08:39.870212Z","caller":"traceutil/trace.go:171","msg":"trace[58453164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8706; }","duration":"163.447921ms","start":"2026-04-17T06:08:39.706729Z","end":"2026-04-17T06:08:39.870177Z","steps":["trace[58453164] 'range keys from in-memory index tree'  (duration: 162.883955ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:09:42.006135Z","caller":"traceutil/trace.go:171","msg":"trace[1058720029] transaction","detail":"{read_only:false; response_revision:9402; number_of_response:1; }","duration":"132.795955ms","start":"2026-04-17T06:09:41.873303Z","end":"2026-04-17T06:09:42.006099Z","steps":["trace[1058720029] 'process raft request'  (duration: 132.537408ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:09:50.424078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6554}
{"level":"info","ts":"2026-04-17T06:09:50.52088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6554,"took":"95.15718ms","hash":28249361,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":26759168,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-04-17T06:09:50.520954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":28249361,"revision":6554,"compact-revision":3250}
{"level":"info","ts":"2026-04-17T06:09:59.033442Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"71d28e9ac1bc4e5d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-17T06:09:59.04217Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-04-17T06:09:59.042271Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-04-17T06:10:42.9161Z","caller":"traceutil/trace.go:171","msg":"trace[2110369506] linearizableReadLoop","detail":"{readStateIndex:10420; appliedIndex:10419; }","duration":"206.242659ms","start":"2026-04-17T06:10:42.709471Z","end":"2026-04-17T06:10:42.915713Z","steps":["trace[2110369506] 'read index received'  (duration: 206.006112ms)","trace[2110369506] 'applied index is now lower than readState.Index'  (duration: 235.607µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-17T06:10:42.917628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.736641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-04-17T06:10:42.917954Z","caller":"traceutil/trace.go:171","msg":"trace[1014297095] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:9968; }","duration":"147.08263ms","start":"2026-04-17T06:10:42.770857Z","end":"2026-04-17T06:10:42.91794Z","steps":["trace[1014297095] 'agreement among raft nodes before linearized reading'  (duration: 146.198675ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-17T06:10:42.916896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.39601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-17T06:10:42.918738Z","caller":"traceutil/trace.go:171","msg":"trace[1865656965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9968; }","duration":"209.278473ms","start":"2026-04-17T06:10:42.70944Z","end":"2026-04-17T06:10:42.918719Z","steps":["trace[1865656965] 'agreement among raft nodes before linearized reading'  (duration: 207.3848ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:10:42.918113Z","caller":"traceutil/trace.go:171","msg":"trace[846033324] transaction","detail":"{read_only:false; response_revision:9968; number_of_response:1; }","duration":"255.009601ms","start":"2026-04-17T06:10:42.661552Z","end":"2026-04-17T06:10:42.916561Z","steps":["trace[846033324] 'process raft request'  (duration: 253.996683ms)"],"step_count":1}
{"level":"info","ts":"2026-04-17T06:14:50.431341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9483}
{"level":"info","ts":"2026-04-17T06:14:50.527529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9483,"took":"91.772874ms","hash":4239460543,"current-db-size-bytes":60813312,"current-db-size":"61 MB","current-db-size-in-use-bytes":25587712,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-04-17T06:14:50.527613Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4239460543,"revision":9483,"compact-revision":6554}