{"level":"warn","ts":"2026-04-18T09:10:08.495452Z","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-18T09:10:08.495571Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.209: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.209:2380","--initial-cluster=instance=https://199.204.45.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.209: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-18T09:10:08.495653Z","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-18T09:10:08.495665Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.209:2380"]}
{"level":"info","ts":"2026-04-18T09:10:08.495684Z","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-18T09:10:08.496383Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"]}
{"level":"info","ts":"2026-04-18T09:10:08.496455Z","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.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.209: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-18T09:10:08.500414Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.715335ms"}
{"level":"info","ts":"2026-04-18T09:10:08.511635Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adfb641115bf6cbd","cluster-id":"3da6b677d4b80c6"}
{"level":"info","ts":"2026-04-18T09:10:08.511714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=()"}
{"level":"info","ts":"2026-04-18T09:10:08.511737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 0"}
{"level":"info","ts":"2026-04-18T09:10:08.511752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adfb641115bf6cbd [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-18T09:10:08.511777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became follower at term 1"}
{"level":"info","ts":"2026-04-18T09:10:08.511818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"}
{"level":"warn","ts":"2026-04-18T09:10:08.518223Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-18T09:10:08.520061Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-18T09:10:08.521982Z","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-18T09:10:08.525323Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adfb641115bf6cbd","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-18T09:10:08.526786Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adfb641115bf6cbd","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-18T09:10:08.526996Z","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-18T09:10:08.527063Z","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-18T09:10:08.527081Z","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-18T09:10:08.529285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd switched to configuration voters=(12536724012257995965)"}
{"level":"info","ts":"2026-04-18T09:10:08.529943Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","added-peer-id":"adfb641115bf6cbd","added-peer-peer-urls":["https://199.204.45.209:2380"]}
{"level":"info","ts":"2026-04-18T09:10:08.530902Z","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-18T09:10:08.531038Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.209:2380"}
{"level":"info","ts":"2026-04-18T09:10:08.53109Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.209:2380"}
{"level":"info","ts":"2026-04-18T09:10:08.5312Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adfb641115bf6cbd","initial-advertise-peer-urls":["https://199.204.45.209:2380"],"listen-peer-urls":["https://199.204.45.209:2380"],"advertise-client-urls":["https://199.204.45.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-18T09:10:08.531246Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-18T09:10:09.112489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd is starting a new election at term 1"}
{"level":"info","ts":"2026-04-18T09:10:09.112552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-18T09:10:09.112582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgPreVoteResp from adfb641115bf6cbd at term 1"}
{"level":"info","ts":"2026-04-18T09:10:09.112597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became candidate at term 2"}
{"level":"info","ts":"2026-04-18T09:10:09.112605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd received MsgVoteResp from adfb641115bf6cbd at term 2"}
{"level":"info","ts":"2026-04-18T09:10:09.112615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adfb641115bf6cbd became leader at term 2"}
{"level":"info","ts":"2026-04-18T09:10:09.112624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adfb641115bf6cbd elected leader adfb641115bf6cbd at term 2"}
{"level":"info","ts":"2026-04-18T09:10:09.113638Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adfb641115bf6cbd","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.209:2379]}","request-path":"/0/members/adfb641115bf6cbd/attributes","cluster-id":"3da6b677d4b80c6","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-18T09:10:09.113682Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-18T09:10:09.113923Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-18T09:10:09.113705Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-18T09:10:09.113958Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-18T09:10:09.113639Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-18T09:10:09.114964Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3da6b677d4b80c6","local-member-id":"adfb641115bf6cbd","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-18T09:10:09.115092Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-18T09:10:09.115125Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-18T09:10:09.115885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.209:2379"}
{"level":"info","ts":"2026-04-18T09:10:09.116876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-18T09:10:40.854694Z","caller":"traceutil/trace.go:171","msg":"trace[1741419141] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"169.247254ms","start":"2026-04-18T09:10:40.685417Z","end":"2026-04-18T09:10:40.854664Z","steps":["trace[1741419141] 'process raft request'  (duration: 169.084079ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.863938Z","caller":"traceutil/trace.go:171","msg":"trace[1590785081] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"176.417577ms","start":"2026-04-18T09:10:40.687486Z","end":"2026-04-18T09:10:40.863904Z","steps":["trace[1590785081] 'process raft request'  (duration: 176.235342ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.86399Z","caller":"traceutil/trace.go:171","msg":"trace[972216652] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"175.560393ms","start":"2026-04-18T09:10:40.688385Z","end":"2026-04-18T09:10:40.863945Z","steps":["trace[972216652] 'process raft request'  (duration: 175.404938ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.864098Z","caller":"traceutil/trace.go:171","msg":"trace[1240408985] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"169.451579ms","start":"2026-04-18T09:10:40.694617Z","end":"2026-04-18T09:10:40.864068Z","steps":["trace[1240408985] 'process raft request'  (duration: 169.249523ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.864084Z","caller":"traceutil/trace.go:171","msg":"trace[2123378909] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"169.452669ms","start":"2026-04-18T09:10:40.694546Z","end":"2026-04-18T09:10:40.863998Z","steps":["trace[2123378909] 'process raft request'  (duration: 169.283454ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.864172Z","caller":"traceutil/trace.go:171","msg":"trace[184247101] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"169.241333ms","start":"2026-04-18T09:10:40.694867Z","end":"2026-04-18T09:10:40.864108Z","steps":["trace[184247101] 'process raft request'  (duration: 169.042988ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.864227Z","caller":"traceutil/trace.go:171","msg":"trace[719628247] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"168.094442ms","start":"2026-04-18T09:10:40.696103Z","end":"2026-04-18T09:10:40.864197Z","steps":["trace[719628247] 'process raft request'  (duration: 167.888706ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.864188Z","caller":"traceutil/trace.go:171","msg":"trace[101391749] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"168.129562ms","start":"2026-04-18T09:10:40.695983Z","end":"2026-04-18T09:10:40.864113Z","steps":["trace[101391749] 'process raft request'  (duration: 167.967208ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.864873Z","caller":"traceutil/trace.go:171","msg":"trace[2058282520] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"150.488422ms","start":"2026-04-18T09:10:40.714362Z","end":"2026-04-18T09:10:40.86485Z","steps":["trace[2058282520] 'process raft request'  (duration: 149.781351ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.864991Z","caller":"traceutil/trace.go:171","msg":"trace[1491546723] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"141.496727ms","start":"2026-04-18T09:10:40.723486Z","end":"2026-04-18T09:10:40.864983Z","steps":["trace[1491546723] 'process raft request'  (duration: 141.354603ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:40.865067Z","caller":"traceutil/trace.go:171","msg":"trace[1135460610] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"124.242597ms","start":"2026-04-18T09:10:40.740801Z","end":"2026-04-18T09:10:40.865044Z","steps":["trace[1135460610] 'process raft request'  (duration: 124.115684ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:46.816104Z","caller":"traceutil/trace.go:171","msg":"trace[205921003] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"154.122695ms","start":"2026-04-18T09:10:46.661959Z","end":"2026-04-18T09:10:46.816082Z","steps":["trace[205921003] 'process raft request'  (duration: 153.534529ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:10:47.038418Z","caller":"traceutil/trace.go:171","msg":"trace[1129570511] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"209.113925ms","start":"2026-04-18T09:10:46.829272Z","end":"2026-04-18T09:10:47.038386Z","steps":["trace[1129570511] 'process raft request'  (duration: 164.456317ms)","trace[1129570511] 'compare'  (duration: 44.481173ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:10:47.038561Z","caller":"traceutil/trace.go:171","msg":"trace[1806407232] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"171.117987ms","start":"2026-04-18T09:10:46.867415Z","end":"2026-04-18T09:10:47.038533Z","steps":["trace[1806407232] 'process raft request'  (duration: 170.899411ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:10:47.523403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.252868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-04-18T09:10:47.523519Z","caller":"traceutil/trace.go:171","msg":"trace[1425014870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:624; }","duration":"377.450154ms","start":"2026-04-18T09:10:47.14605Z","end":"2026-04-18T09:10:47.5235Z","steps":["trace[1425014870] 'range keys from in-memory index tree'  (duration: 377.123905ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:10:47.523562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:10:47.14603Z","time spent":"377.521506ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-18T09:12:20.601241Z","caller":"traceutil/trace.go:171","msg":"trace[1268934095] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1917; }","duration":"265.258932ms","start":"2026-04-18T09:12:20.335952Z","end":"2026-04-18T09:12:20.601211Z","steps":["trace[1268934095] 'read index received'  (duration: 265.036436ms)","trace[1268934095] 'applied index is now lower than readState.Index'  (duration: 209.676µs)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:12:20.601277Z","caller":"traceutil/trace.go:171","msg":"trace[964619893] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"291.906598ms","start":"2026-04-18T09:12:20.309344Z","end":"2026-04-18T09:12:20.60125Z","steps":["trace[964619893] 'process raft request'  (duration: 291.703162ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:12:20.618158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.190223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"warn","ts":"2026-04-18T09:12:20.618221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.391981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-18T09:12:20.618231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.49592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f1be0ce8-e059-4912-aff9-0747a658e519\" ","response":"range_response_count:1 size:3747"}
{"level":"info","ts":"2026-04-18T09:12:20.618251Z","caller":"traceutil/trace.go:171","msg":"trace[364888068] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1846; }","duration":"282.293506ms","start":"2026-04-18T09:12:20.335933Z","end":"2026-04-18T09:12:20.618226Z","steps":["trace[364888068] 'agreement among raft nodes before linearized reading'  (duration: 265.406787ms)","trace[364888068] 'range keys from in-memory index tree'  (duration: 16.703124ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:12:20.618281Z","caller":"traceutil/trace.go:171","msg":"trace[801518587] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f1be0ce8-e059-4912-aff9-0747a658e519; range_end:; response_count:1; response_revision:1846; }","duration":"245.567672ms","start":"2026-04-18T09:12:20.372701Z","end":"2026-04-18T09:12:20.618269Z","steps":["trace[801518587] 'agreement among raft nodes before linearized reading'  (duration: 228.639042ms)","trace[801518587] 'range keys from in-memory index tree'  (duration: 16.847128ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:12:20.618319Z","caller":"traceutil/trace.go:171","msg":"trace[1992459208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1846; }","duration":"112.486744ms","start":"2026-04-18T09:12:20.505776Z","end":"2026-04-18T09:12:20.618263Z","steps":["trace[1992459208] 'agreement among raft nodes before linearized reading'  (duration: 95.588724ms)","trace[1992459208] 'count revisions from in-memory index tree'  (duration: 16.807447ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-18T09:12:20.618363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.49731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-18T09:12:20.618392Z","caller":"traceutil/trace.go:171","msg":"trace[862334986] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1846; }","duration":"183.795728ms","start":"2026-04-18T09:12:20.434589Z","end":"2026-04-18T09:12:20.618385Z","steps":["trace[862334986] 'agreement among raft nodes before linearized reading'  (duration: 166.783286ms)","trace[862334986] 'count revisions from in-memory index tree'  (duration: 16.765076ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-18T09:12:20.618355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.169097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-18T09:12:20.618477Z","caller":"traceutil/trace.go:171","msg":"trace[219232919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1846; }","duration":"123.350602ms","start":"2026-04-18T09:12:20.495098Z","end":"2026-04-18T09:12:20.618448Z","steps":["trace[219232919] 'agreement among raft nodes before linearized reading'  (duration: 106.272548ms)","trace[219232919] 'range keys from in-memory index tree'  (duration: 16.766476ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:12:20.799018Z","caller":"traceutil/trace.go:171","msg":"trace[1283625526] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"176.560743ms","start":"2026-04-18T09:12:20.622425Z","end":"2026-04-18T09:12:20.798986Z","steps":["trace[1283625526] 'process raft request'  (duration: 169.581745ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:12:20.79907Z","caller":"traceutil/trace.go:171","msg":"trace[1119693603] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"169.899745ms","start":"2026-04-18T09:12:20.629139Z","end":"2026-04-18T09:12:20.799038Z","steps":["trace[1119693603] 'process raft request'  (duration: 169.791862ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:12:20.799029Z","caller":"traceutil/trace.go:171","msg":"trace[215722776] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1919; }","duration":"146.524902ms","start":"2026-04-18T09:12:20.652476Z","end":"2026-04-18T09:12:20.799001Z","steps":["trace[215722776] 'read index received'  (duration: 139.464991ms)","trace[215722776] 'applied index is now lower than readState.Index'  (duration: 7.059131ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-18T09:12:20.799229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.730688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-04-18T09:12:20.799264Z","caller":"traceutil/trace.go:171","msg":"trace[551880996] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"176.42466ms","start":"2026-04-18T09:12:20.622828Z","end":"2026-04-18T09:12:20.799252Z","steps":["trace[551880996] 'process raft request'  (duration: 176.039989ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:12:20.799279Z","caller":"traceutil/trace.go:171","msg":"trace[876682146] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1850; }","duration":"146.796509ms","start":"2026-04-18T09:12:20.652467Z","end":"2026-04-18T09:12:20.799264Z","steps":["trace[876682146] 'agreement among raft nodes before linearized reading'  (duration: 146.621004ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:13:10.105906Z","caller":"traceutil/trace.go:171","msg":"trace[1982182091] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2250; }","duration":"145.751264ms","start":"2026-04-18T09:13:09.96012Z","end":"2026-04-18T09:13:10.105871Z","steps":["trace[1982182091] 'read index received'  (duration: 145.469326ms)","trace[1982182091] 'applied index is now lower than readState.Index'  (duration: 280.378µs)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:13:10.105973Z","caller":"traceutil/trace.go:171","msg":"trace[1394736148] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"155.386678ms","start":"2026-04-18T09:13:09.950547Z","end":"2026-04-18T09:13:10.105934Z","steps":["trace[1394736148] 'process raft request'  (duration: 155.12735ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:13:10.106092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.939829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-18T09:13:10.106157Z","caller":"traceutil/trace.go:171","msg":"trace[1119540684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2168; }","duration":"146.059102ms","start":"2026-04-18T09:13:09.960079Z","end":"2026-04-18T09:13:10.106138Z","steps":["trace[1119540684] 'agreement among raft nodes before linearized reading'  (duration: 145.936439ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:13:10.479993Z","caller":"traceutil/trace.go:171","msg":"trace[695119009] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2251; }","duration":"186.78505ms","start":"2026-04-18T09:13:10.293182Z","end":"2026-04-18T09:13:10.479967Z","steps":["trace[695119009] 'read index received'  (duration: 186.591404ms)","trace[695119009] 'applied index is now lower than readState.Index'  (duration: 192.816µs)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:13:10.480045Z","caller":"traceutil/trace.go:171","msg":"trace[1950977310] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"193.571842ms","start":"2026-04-18T09:13:10.286449Z","end":"2026-04-18T09:13:10.480021Z","steps":["trace[1950977310] 'process raft request'  (duration: 193.375857ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:13:10.480224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.019957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-04-18T09:13:10.480412Z","caller":"traceutil/trace.go:171","msg":"trace[863948381] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2169; }","duration":"187.092199ms","start":"2026-04-18T09:13:10.293164Z","end":"2026-04-18T09:13:10.480256Z","steps":["trace[863948381] 'agreement among raft nodes before linearized reading'  (duration: 186.928084ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:14:27.549376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.398939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"warn","ts":"2026-04-18T09:14:27.549455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.923032ms","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-04-18T09:14:27.549477Z","caller":"traceutil/trace.go:171","msg":"trace[632171240] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2636; }","duration":"108.563394ms","start":"2026-04-18T09:14:27.440893Z","end":"2026-04-18T09:14:27.549457Z","steps":["trace[632171240] 'range keys from in-memory index tree'  (duration: 108.244615ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:14:27.549526Z","caller":"traceutil/trace.go:171","msg":"trace[540297508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2636; }","duration":"111.026624ms","start":"2026-04-18T09:14:27.438484Z","end":"2026-04-18T09:14:27.549511Z","steps":["trace[540297508] 'range keys from in-memory index tree'  (duration: 110.808008ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:14:32.999305Z","caller":"traceutil/trace.go:171","msg":"trace[1583219508] transaction","detail":"{read_only:false; response_revision:2666; number_of_response:1; }","duration":"117.378525ms","start":"2026-04-18T09:14:32.881907Z","end":"2026-04-18T09:14:32.999286Z","steps":["trace[1583219508] 'process raft request'  (duration: 117.21361ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:17.24411Z","caller":"traceutil/trace.go:171","msg":"trace[574979140] linearizableReadLoop","detail":"{readStateIndex:4790; appliedIndex:4789; }","duration":"136.708963ms","start":"2026-04-18T09:18:17.107352Z","end":"2026-04-18T09:18:17.244061Z","steps":["trace[574979140] 'read index received'  (duration: 136.476247ms)","trace[574979140] 'applied index is now lower than readState.Index'  (duration: 231.376µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-18T09:18:17.244421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.028273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"}
{"level":"info","ts":"2026-04-18T09:18:17.244458Z","caller":"traceutil/trace.go:171","msg":"trace[398597906] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4611; }","duration":"137.135136ms","start":"2026-04-18T09:18:17.107313Z","end":"2026-04-18T09:18:17.244449Z","steps":["trace[398597906] 'agreement among raft nodes before linearized reading'  (duration: 136.91468ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:17.244503Z","caller":"traceutil/trace.go:171","msg":"trace[1399548219] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"143.129606ms","start":"2026-04-18T09:18:17.10104Z","end":"2026-04-18T09:18:17.24417Z","steps":["trace[1399548219] 'process raft request'  (duration: 142.812437ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:17.304567Z","caller":"traceutil/trace.go:171","msg":"trace[990699176] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"152.294905ms","start":"2026-04-18T09:18:17.152245Z","end":"2026-04-18T09:18:17.30454Z","steps":["trace[990699176] 'process raft request'  (duration: 152.040018ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:17.304623Z","caller":"traceutil/trace.go:171","msg":"trace[135450837] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"139.317548ms","start":"2026-04-18T09:18:17.165295Z","end":"2026-04-18T09:18:17.304613Z","steps":["trace[135450837] 'process raft request'  (duration: 139.146154ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:17.30474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.350485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-18T09:18:17.305326Z","caller":"traceutil/trace.go:171","msg":"trace[455162568] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4613; }","duration":"129.976823ms","start":"2026-04-18T09:18:17.175328Z","end":"2026-04-18T09:18:17.305305Z","steps":["trace[455162568] 'agreement among raft nodes before linearized reading'  (duration: 129.312534ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:17.366146Z","caller":"traceutil/trace.go:171","msg":"trace[622514240] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"102.976668ms","start":"2026-04-18T09:18:17.26312Z","end":"2026-04-18T09:18:17.366097Z","steps":["trace[622514240] 'process raft request'  (duration: 102.747672ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:17.366305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.826175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"}
{"level":"info","ts":"2026-04-18T09:18:17.366407Z","caller":"traceutil/trace.go:171","msg":"trace[969636871] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4614; }","duration":"107.968009ms","start":"2026-04-18T09:18:17.258411Z","end":"2026-04-18T09:18:17.366379Z","steps":["trace[969636871] 'agreement among raft nodes before linearized reading'  (duration: 107.688411ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:20.91244Z","caller":"traceutil/trace.go:171","msg":"trace[925568266] linearizableReadLoop","detail":"{readStateIndex:4946; appliedIndex:4945; }","duration":"158.215445ms","start":"2026-04-18T09:18:20.754199Z","end":"2026-04-18T09:18:20.912415Z","steps":["trace[925568266] 'read index received'  (duration: 123.399188ms)","trace[925568266] 'applied index is now lower than readState.Index'  (duration: 34.815507ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:18:20.912537Z","caller":"traceutil/trace.go:171","msg":"trace[77496922] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"170.890893ms","start":"2026-04-18T09:18:20.741609Z","end":"2026-04-18T09:18:20.912499Z","steps":["trace[77496922] 'process raft request'  (duration: 136.076697ms)","trace[77496922] 'compare'  (duration: 34.643141ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-18T09:18:20.912626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.42153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"}
{"level":"info","ts":"2026-04-18T09:18:20.912657Z","caller":"traceutil/trace.go:171","msg":"trace[45916546] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4764; }","duration":"158.492452ms","start":"2026-04-18T09:18:20.754156Z","end":"2026-04-18T09:18:20.912648Z","steps":["trace[45916546] 'agreement among raft nodes before linearized reading'  (duration: 158.384659ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:20.912627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.599038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-04-18T09:18:20.912745Z","caller":"traceutil/trace.go:171","msg":"trace[2134859975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4764; }","duration":"156.765103ms","start":"2026-04-18T09:18:20.755974Z","end":"2026-04-18T09:18:20.912739Z","steps":["trace[2134859975] 'agreement among raft nodes before linearized reading'  (duration: 156.573278ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:20.912822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.89667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-04-18T09:18:20.912849Z","caller":"traceutil/trace.go:171","msg":"trace[671763718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4764; }","duration":"148.949121ms","start":"2026-04-18T09:18:20.763892Z","end":"2026-04-18T09:18:20.912841Z","steps":["trace[671763718] 'agreement among raft nodes before linearized reading'  (duration: 148.876749ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:32.276623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.618686ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7835592236590410903 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:6cbd9d9fdac7ac96>","response":"size:40"}
{"level":"info","ts":"2026-04-18T09:18:32.287103Z","caller":"traceutil/trace.go:171","msg":"trace[1168706962] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"144.415562ms","start":"2026-04-18T09:18:32.142665Z","end":"2026-04-18T09:18:32.287081Z","steps":["trace[1168706962] 'process raft request'  (duration: 144.201706ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:32.287164Z","caller":"traceutil/trace.go:171","msg":"trace[1411520713] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"142.471707ms","start":"2026-04-18T09:18:32.144672Z","end":"2026-04-18T09:18:32.287144Z","steps":["trace[1411520713] 'process raft request'  (duration: 142.396325ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:32.287214Z","caller":"traceutil/trace.go:171","msg":"trace[1302452385] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"142.845998ms","start":"2026-04-18T09:18:32.144329Z","end":"2026-04-18T09:18:32.287175Z","steps":["trace[1302452385] 'process raft request'  (duration: 142.685284ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:32.287324Z","caller":"traceutil/trace.go:171","msg":"trace[182981006] linearizableReadLoop","detail":"{readStateIndex:5184; appliedIndex:5180; }","duration":"142.535889ms","start":"2026-04-18T09:18:32.144777Z","end":"2026-04-18T09:18:32.287313Z","steps":["trace[182981006] 'read index received'  (duration: 16.049725ms)","trace[182981006] 'applied index is now lower than readState.Index'  (duration: 126.484824ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:18:32.287393Z","caller":"traceutil/trace.go:171","msg":"trace[894415043] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"141.060407ms","start":"2026-04-18T09:18:32.146324Z","end":"2026-04-18T09:18:32.287384Z","steps":["trace[894415043] 'process raft request'  (duration: 140.780079ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:32.287572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.774496ms","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-04-18T09:18:32.287631Z","caller":"traceutil/trace.go:171","msg":"trace[2072038001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4995; }","duration":"142.877039ms","start":"2026-04-18T09:18:32.144736Z","end":"2026-04-18T09:18:32.287613Z","steps":["trace[2072038001] 'agreement among raft nodes before linearized reading'  (duration: 142.694703ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:32.302096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.711132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-04-18T09:18:32.302239Z","caller":"traceutil/trace.go:171","msg":"trace[1520824344] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4996; }","duration":"131.864687ms","start":"2026-04-18T09:18:32.170343Z","end":"2026-04-18T09:18:32.302208Z","steps":["trace[1520824344] 'agreement among raft nodes before linearized reading'  (duration: 131.490077ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:32.6264Z","caller":"traceutil/trace.go:171","msg":"trace[643034725] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"120.400662ms","start":"2026-04-18T09:18:32.505974Z","end":"2026-04-18T09:18:32.626375Z","steps":["trace[643034725] 'process raft request'  (duration: 120.254238ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:40.290432Z","caller":"traceutil/trace.go:171","msg":"trace[725991511] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"124.923829ms","start":"2026-04-18T09:18:40.165472Z","end":"2026-04-18T09:18:40.290396Z","steps":["trace[725991511] 'process raft request'  (duration: 124.766315ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:18:40.584955Z","caller":"traceutil/trace.go:171","msg":"trace[1645074090] linearizableReadLoop","detail":"{readStateIndex:5319; appliedIndex:5318; }","duration":"223.788012ms","start":"2026-04-18T09:18:40.361137Z","end":"2026-04-18T09:18:40.584925Z","steps":["trace[1645074090] 'read index received'  (duration: 221.111456ms)","trace[1645074090] 'applied index is now lower than readState.Index'  (duration: 2.674705ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:18:40.584979Z","caller":"traceutil/trace.go:171","msg":"trace[1497661975] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"232.054906ms","start":"2026-04-18T09:18:40.352875Z","end":"2026-04-18T09:18:40.58493Z","steps":["trace[1497661975] 'process raft request'  (duration: 229.37165ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:40.585258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.08702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-18T09:18:40.585328Z","caller":"traceutil/trace.go:171","msg":"trace[351530029] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5127; }","duration":"224.172162ms","start":"2026-04-18T09:18:40.361133Z","end":"2026-04-18T09:18:40.585305Z","steps":["trace[351530029] 'agreement among raft nodes before linearized reading'  (duration: 223.949816ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:18:40.585921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.863502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mp2mr\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-04-18T09:18:40.586009Z","caller":"traceutil/trace.go:171","msg":"trace[1784559788] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mp2mr; range_end:; response_count:1; response_revision:5127; }","duration":"212.972505ms","start":"2026-04-18T09:18:40.373016Z","end":"2026-04-18T09:18:40.585989Z","steps":["trace[1784559788] 'agreement among raft nodes before linearized reading'  (duration: 212.691718ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:20:09.138749Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855}
{"level":"info","ts":"2026-04-18T09:20:09.207488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"67.029321ms","hash":391609786,"current-db-size-bytes":58318848,"current-db-size":"58 MB","current-db-size-in-use-bytes":33214464,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-04-18T09:20:09.207578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":391609786,"revision":2855,"compact-revision":-1}
{"level":"warn","ts":"2026-04-18T09:24:41.88256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.626004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-04-18T09:24:41.882654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.520222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-18T09:24:41.882714Z","caller":"traceutil/trace.go:171","msg":"trace[819087855] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:7979; }","duration":"169.812679ms","start":"2026-04-18T09:24:41.712858Z","end":"2026-04-18T09:24:41.882671Z","steps":["trace[819087855] 'count revisions from in-memory index tree'  (duration: 169.50115ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:24:41.882758Z","caller":"traceutil/trace.go:171","msg":"trace[1149298605] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:7979; }","duration":"156.700577ms","start":"2026-04-18T09:24:41.726029Z","end":"2026-04-18T09:24:41.882729Z","steps":["trace[1149298605] 'count revisions from in-memory index tree'  (duration: 156.376627ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:24:42.691692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.928568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mp2mr\" ","response":"range_response_count:1 size:6531"}
{"level":"info","ts":"2026-04-18T09:24:42.691784Z","caller":"traceutil/trace.go:171","msg":"trace[1510244619] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mp2mr; range_end:; response_count:1; response_revision:7982; }","duration":"121.078654ms","start":"2026-04-18T09:24:42.570688Z","end":"2026-04-18T09:24:42.691767Z","steps":["trace[1510244619] 'range keys from in-memory index tree'  (duration: 120.697352ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:24:42.691818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.521254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"}
{"level":"info","ts":"2026-04-18T09:24:42.691914Z","caller":"traceutil/trace.go:171","msg":"trace[1675472721] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7982; }","duration":"334.666038ms","start":"2026-04-18T09:24:42.357225Z","end":"2026-04-18T09:24:42.691891Z","steps":["trace[1675472721] 'range keys from in-memory index tree'  (duration: 334.336778ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:24:42.69207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.096465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-04-18T09:24:42.692194Z","caller":"traceutil/trace.go:171","msg":"trace[1073503304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7982; }","duration":"287.2712ms","start":"2026-04-18T09:24:42.404899Z","end":"2026-04-18T09:24:42.69217Z","steps":["trace[1073503304] 'range keys from in-memory index tree'  (duration: 286.878049ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:24:42.692347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.263176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-04-18T09:24:42.692381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.494515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-04-18T09:24:42.692414Z","caller":"traceutil/trace.go:171","msg":"trace[1859161642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7982; }","duration":"289.353339ms","start":"2026-04-18T09:24:42.403046Z","end":"2026-04-18T09:24:42.692399Z","steps":["trace[1859161642] 'range keys from in-memory index tree'  (duration: 289.161914ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:24:42.69242Z","caller":"traceutil/trace.go:171","msg":"trace[1071380563] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7982; }","duration":"269.555206ms","start":"2026-04-18T09:24:42.422852Z","end":"2026-04-18T09:24:42.692407Z","steps":["trace[1071380563] 'range keys from in-memory index tree'  (duration: 269.396011ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:24:42.692718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:24:42.357204Z","time spent":"335.497091ms","remote":"127.0.0.1:59302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"info","ts":"2026-04-18T09:24:43.211815Z","caller":"traceutil/trace.go:171","msg":"trace[424163272] transaction","detail":"{read_only:false; response_revision:7990; number_of_response:1; }","duration":"146.890459ms","start":"2026-04-18T09:24:43.064895Z","end":"2026-04-18T09:24:43.211785Z","steps":["trace[424163272] 'process raft request'  (duration: 92.706507ms)","trace[424163272] 'compare'  (duration: 54.063089ms)"],"step_count":2}
{"level":"info","ts":"2026-04-18T09:25:09.14501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5957}
{"level":"info","ts":"2026-04-18T09:25:09.235486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5957,"took":"88.963019ms","hash":1750058675,"current-db-size-bytes":58318848,"current-db-size":"58 MB","current-db-size-in-use-bytes":22679552,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-04-18T09:25:09.235564Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1750058675,"revision":5957,"compact-revision":2855}
{"level":"info","ts":"2026-04-18T09:25:09.686356Z","caller":"traceutil/trace.go:171","msg":"trace[1278379267] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"219.174163ms","start":"2026-04-18T09:25:09.467152Z","end":"2026-04-18T09:25:09.686326Z","steps":["trace[1278379267] 'process raft request'  (duration: 217.796424ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:25:09.814126Z","caller":"traceutil/trace.go:171","msg":"trace[39134135] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"117.033919ms","start":"2026-04-18T09:25:09.697068Z","end":"2026-04-18T09:25:09.814102Z","steps":["trace[39134135] 'process raft request'  (duration: 115.379492ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-18T09:25:23.797624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.764894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"}
{"level":"warn","ts":"2026-04-18T09:25:23.79769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.515996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-18T09:25:23.797733Z","caller":"traceutil/trace.go:171","msg":"trace[1873314881] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8303; }","duration":"139.948039ms","start":"2026-04-18T09:25:23.657761Z","end":"2026-04-18T09:25:23.79771Z","steps":["trace[1873314881] 'range keys from in-memory index tree'  (duration: 139.543128ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:25:23.797772Z","caller":"traceutil/trace.go:171","msg":"trace[365325765] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8303; }","duration":"127.64278ms","start":"2026-04-18T09:25:23.670109Z","end":"2026-04-18T09:25:23.797752Z","steps":["trace[365325765] 'range keys from in-memory index tree'  (duration: 127.458584ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:25:24.340024Z","caller":"traceutil/trace.go:171","msg":"trace[52734767] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"133.670571ms","start":"2026-04-18T09:25:24.206333Z","end":"2026-04-18T09:25:24.340003Z","steps":["trace[52734767] 'process raft request'  (duration: 133.496366ms)"],"step_count":1}
{"level":"info","ts":"2026-04-18T09:26:33.943091Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002450.wal"}
{"level":"info","ts":"2026-04-18T09:27:57.294936Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adfb641115bf6cbd","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-18T09:27:57.30021Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-04-18T09:27:57.300316Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-04-18T09:30:09.152484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8182}
{"level":"info","ts":"2026-04-18T09:30:09.232812Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8182,"took":"77.896433ms","hash":4287202848,"current-db-size-bytes":58318848,"current-db-size":"58 MB","current-db-size-in-use-bytes":24412160,"current-db-size-in-use":"24 MB"}
{"level":"info","ts":"2026-04-18T09:30:09.23289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4287202848,"revision":8182,"compact-revision":5957}
{"level":"info","ts":"2026-04-18T09:35:09.160894Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10565}
{"level":"info","ts":"2026-04-18T09:35:09.248823Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10565,"took":"85.634308ms","hash":146631291,"current-db-size-bytes":58318848,"current-db-size":"58 MB","current-db-size-in-use-bytes":19914752,"current-db-size-in-use":"20 MB"}
{"level":"info","ts":"2026-04-18T09:35:09.248937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":146631291,"revision":10565,"compact-revision":8182}