{"level":"warn","ts":"2026-03-18T21:48:25.502681Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T21:48:25.502771Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-18T21:48:25.502839Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T21:48:25.502847Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-03-18T21:48:25.502867Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T21:48:25.503465Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-03-18T21:48:25.503539Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-18T21:48:25.509373Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.62011ms"} {"level":"info","ts":"2026-03-18T21:48:25.524508Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-03-18T21:48:25.524592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T21:48:25.524615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-03-18T21:48:25.524627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T21:48:25.524636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-03-18T21:48:25.524715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-03-18T21:48:25.534667Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T21:48:25.536217Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T21:48:25.537427Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-18T21:48:25.539506Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T21:48:25.539709Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T21:48:25.539801Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T21:48:25.539995Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T21:48:25.540029Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T21:48:25.542124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-03-18T21:48:25.542639Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-03-18T21:48:25.544538Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T21:48:25.544669Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-03-18T21:48:25.54478Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-03-18T21:48:25.544815Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T21:48:25.544848Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T21:48:26.125186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T21:48:26.12526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T21:48:26.125297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-03-18T21:48:26.12532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-03-18T21:48:26.125332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-03-18T21:48:26.125349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-03-18T21:48:26.125366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-03-18T21:48:26.12649Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T21:48:26.126497Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T21:48:26.126518Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:26.126715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T21:48:26.126903Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T21:48:26.126965Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T21:48:26.127748Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:26.127885Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:26.127926Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T21:48:26.130725Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T21:48:26.13601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-03-18T21:48:47.986305Z","caller":"traceutil/trace.go:171","msg":"trace[441924423] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"121.080214ms","start":"2026-03-18T21:48:47.865166Z","end":"2026-03-18T21:48:47.986247Z","steps":["trace[441924423] 'process raft request' (duration: 120.686566ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:02.203479Z","caller":"traceutil/trace.go:171","msg":"trace[1903851008] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"143.051352ms","start":"2026-03-18T21:49:02.060389Z","end":"2026-03-18T21:49:02.20344Z","steps":["trace[1903851008] 'read index received' (duration: 142.822537ms)","trace[1903851008] 'applied index is now lower than readState.Index' (duration: 227.115µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:49:02.203536Z","caller":"traceutil/trace.go:171","msg":"trace[2026559717] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"189.109421ms","start":"2026-03-18T21:49:02.014379Z","end":"2026-03-18T21:49:02.203488Z","steps":["trace[2026559717] 'process raft request' (duration: 188.860125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:49:02.203773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.348758ms","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-03-18T21:49:02.203863Z","caller":"traceutil/trace.go:171","msg":"trace[274921423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"143.509961ms","start":"2026-03-18T21:49:02.06034Z","end":"2026-03-18T21:49:02.203849Z","steps":["trace[274921423] 'agreement among raft nodes before linearized reading' (duration: 143.242585ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:22.522635Z","caller":"traceutil/trace.go:171","msg":"trace[482386058] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"153.066281ms","start":"2026-03-18T21:49:22.36905Z","end":"2026-03-18T21:49:22.522116Z","steps":["trace[482386058] 'process raft request' (duration: 152.922558ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:57.414249Z","caller":"traceutil/trace.go:171","msg":"trace[826925080] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"101.560432ms","start":"2026-03-18T21:49:57.312655Z","end":"2026-03-18T21:49:57.414215Z","steps":["trace[826925080] 'process raft request' (duration: 100.932228ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:49:59.395414Z","caller":"traceutil/trace.go:171","msg":"trace[46634002] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"123.693056ms","start":"2026-03-18T21:49:59.271688Z","end":"2026-03-18T21:49:59.395381Z","steps":["trace[46634002] 'process raft request' (duration: 63.492957ms)","trace[46634002] 'compare' (duration: 59.969705ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:04.409203Z","caller":"traceutil/trace.go:171","msg":"trace[654407317] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"114.559828ms","start":"2026-03-18T21:50:04.29461Z","end":"2026-03-18T21:50:04.409169Z","steps":["trace[654407317] 'process raft request' (duration: 101.421577ms)","trace[654407317] 'compare' (duration: 12.896085ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:04.409278Z","caller":"traceutil/trace.go:171","msg":"trace[1308215536] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"114.6248ms","start":"2026-03-18T21:50:04.29463Z","end":"2026-03-18T21:50:04.409255Z","steps":["trace[1308215536] 'process raft request' (duration: 114.459315ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.409421Z","caller":"traceutil/trace.go:171","msg":"trace[510511994] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1378; }","duration":"113.715662ms","start":"2026-03-18T21:50:04.295676Z","end":"2026-03-18T21:50:04.409391Z","steps":["trace[510511994] 'read index received' (duration: 55.242828ms)","trace[510511994] 'applied index is now lower than readState.Index' (duration: 58.471814ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:04.409502Z","caller":"traceutil/trace.go:171","msg":"trace[1371515493] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"113.095878ms","start":"2026-03-18T21:50:04.296393Z","end":"2026-03-18T21:50:04.409489Z","steps":["trace[1371515493] 'process raft request' (duration: 112.868644ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.409546Z","caller":"traceutil/trace.go:171","msg":"trace[1466785539] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"114.749252ms","start":"2026-03-18T21:50:04.294779Z","end":"2026-03-18T21:50:04.409528Z","steps":["trace[1466785539] 'process raft request' (duration: 114.382005ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.409757Z","caller":"traceutil/trace.go:171","msg":"trace[888137459] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"106.385601ms","start":"2026-03-18T21:50:04.303356Z","end":"2026-03-18T21:50:04.409741Z","steps":["trace[888137459] 'process raft request' (duration: 105.992683ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.409773Z","caller":"traceutil/trace.go:171","msg":"trace[1059153255] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"112.016896ms","start":"2026-03-18T21:50:04.297745Z","end":"2026-03-18T21:50:04.409762Z","steps":["trace[1059153255] 'process raft request' (duration: 111.554917ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.410066Z","caller":"traceutil/trace.go:171","msg":"trace[543665269] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"114.388215ms","start":"2026-03-18T21:50:04.295509Z","end":"2026-03-18T21:50:04.409897Z","steps":["trace[543665269] 'process raft request' (duration: 113.706811ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:04.410321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.599479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:5472"} {"level":"info","ts":"2026-03-18T21:50:04.410482Z","caller":"traceutil/trace.go:171","msg":"trace[842279780] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:1335; }","duration":"114.779723ms","start":"2026-03-18T21:50:04.295643Z","end":"2026-03-18T21:50:04.410423Z","steps":["trace[842279780] 'agreement among raft nodes before linearized reading' (duration: 114.11941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:04.434319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.001731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:5755"} {"level":"info","ts":"2026-03-18T21:50:04.434389Z","caller":"traceutil/trace.go:171","msg":"trace[1861949495] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1336; }","duration":"138.098854ms","start":"2026-03-18T21:50:04.296269Z","end":"2026-03-18T21:50:04.434368Z","steps":["trace[1861949495] 'agreement among raft nodes before linearized reading' (duration: 137.907859ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.578599Z","caller":"traceutil/trace.go:171","msg":"trace[2064667436] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"139.9ms","start":"2026-03-18T21:50:04.438662Z","end":"2026-03-18T21:50:04.578562Z","steps":["trace[2064667436] 'process raft request' (duration: 81.719912ms)","trace[2064667436] 'compare' (duration: 57.941533ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:04.57865Z","caller":"traceutil/trace.go:171","msg":"trace[1470087905] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"135.712163ms","start":"2026-03-18T21:50:04.442921Z","end":"2026-03-18T21:50:04.578634Z","steps":["trace[1470087905] 'process raft request' (duration: 135.54518ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.578968Z","caller":"traceutil/trace.go:171","msg":"trace[405500857] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"134.891837ms","start":"2026-03-18T21:50:04.444047Z","end":"2026-03-18T21:50:04.578939Z","steps":["trace[405500857] 'process raft request' (duration: 134.527199ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.579185Z","caller":"traceutil/trace.go:171","msg":"trace[882415605] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"103.167924ms","start":"2026-03-18T21:50:04.475984Z","end":"2026-03-18T21:50:04.579152Z","steps":["trace[882415605] 'process raft request' (duration: 102.880148ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.743559Z","caller":"traceutil/trace.go:171","msg":"trace[495322595] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"110.634699ms","start":"2026-03-18T21:50:04.632885Z","end":"2026-03-18T21:50:04.74352Z","steps":["trace[495322595] 'process raft request' (duration: 80.064879ms)","trace[495322595] 'compare' (duration: 30.289654ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:04.743636Z","caller":"traceutil/trace.go:171","msg":"trace[329731802] linearizableReadLoop","detail":"{readStateIndex:1400; appliedIndex:1399; }","duration":"104.659674ms","start":"2026-03-18T21:50:04.638951Z","end":"2026-03-18T21:50:04.74361Z","steps":["trace[329731802] 'read index received' (duration: 74.007963ms)","trace[329731802] 'applied index is now lower than readState.Index' (duration: 30.649691ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:50:04.753153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.369209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1080"} {"level":"info","ts":"2026-03-18T21:50:04.753266Z","caller":"traceutil/trace.go:171","msg":"trace[792540854] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:1349; }","duration":"120.513321ms","start":"2026-03-18T21:50:04.632737Z","end":"2026-03-18T21:50:04.75325Z","steps":["trace[792540854] 'agreement among raft nodes before linearized reading' (duration: 110.996226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:04.753392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.925869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:133118"} {"level":"warn","ts":"2026-03-18T21:50:04.753286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.894519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T21:50:04.753452Z","caller":"traceutil/trace.go:171","msg":"trace[1814547508] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:1350; }","duration":"102.02027ms","start":"2026-03-18T21:50:04.651418Z","end":"2026-03-18T21:50:04.753438Z","steps":["trace[1814547508] 'agreement among raft nodes before linearized reading' (duration: 101.724034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:04.753201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.209231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-6x4ql\" ","response":"range_response_count:1 size:5780"} {"level":"info","ts":"2026-03-18T21:50:04.753502Z","caller":"traceutil/trace.go:171","msg":"trace[1648200607] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1350; }","duration":"105.119274ms","start":"2026-03-18T21:50:04.648364Z","end":"2026-03-18T21:50:04.753483Z","steps":["trace[1648200607] 'agreement among raft nodes before linearized reading' (duration: 104.883809ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:04.753579Z","caller":"traceutil/trace.go:171","msg":"trace[1742300191] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-messaging-topology-operator-7f8596f788-6x4ql; range_end:; response_count:1; response_revision:1350; }","duration":"113.595528ms","start":"2026-03-18T21:50:04.639924Z","end":"2026-03-18T21:50:04.753519Z","steps":["trace[1742300191] 'agreement among raft nodes before linearized reading' (duration: 113.112488ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:07.219082Z","caller":"traceutil/trace.go:171","msg":"trace[1818711654] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"116.56672ms","start":"2026-03-18T21:50:07.102467Z","end":"2026-03-18T21:50:07.219034Z","steps":["trace[1818711654] 'process raft request' (duration: 76.394882ms)","trace[1818711654] 'compare' (duration: 39.952463ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:09.492133Z","caller":"traceutil/trace.go:171","msg":"trace[844083138] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1403; }","duration":"205.290777ms","start":"2026-03-18T21:50:09.286807Z","end":"2026-03-18T21:50:09.492098Z","steps":["trace[844083138] 'process raft request' (duration: 204.021881ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:09.554862Z","caller":"traceutil/trace.go:171","msg":"trace[1043927278] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"252.442257ms","start":"2026-03-18T21:50:09.3024Z","end":"2026-03-18T21:50:09.554842Z","steps":["trace[1043927278] 'process raft request' (duration: 252.11683ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.508901Z","caller":"traceutil/trace.go:171","msg":"trace[1516064583] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"109.910092ms","start":"2026-03-18T21:50:10.398966Z","end":"2026-03-18T21:50:10.508876Z","steps":["trace[1516064583] 'process raft request' (duration: 107.097554ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.734494Z","caller":"traceutil/trace.go:171","msg":"trace[1009079224] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"130.240941ms","start":"2026-03-18T21:50:10.604222Z","end":"2026-03-18T21:50:10.734463Z","steps":["trace[1009079224] 'process raft request' (duration: 130.076308ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.83809Z","caller":"traceutil/trace.go:171","msg":"trace[61840372] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"166.992978ms","start":"2026-03-18T21:50:10.671048Z","end":"2026-03-18T21:50:10.838041Z","steps":["trace[61840372] 'process raft request' (duration: 166.550969ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.848083Z","caller":"traceutil/trace.go:171","msg":"trace[1903446965] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"107.715448ms","start":"2026-03-18T21:50:10.740345Z","end":"2026-03-18T21:50:10.848061Z","steps":["trace[1903446965] 'process raft request' (duration: 107.571825ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.848061Z","caller":"traceutil/trace.go:171","msg":"trace[1336674532] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"108.387292ms","start":"2026-03-18T21:50:10.739638Z","end":"2026-03-18T21:50:10.848025Z","steps":["trace[1336674532] 'process raft request' (duration: 108.138437ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.848068Z","caller":"traceutil/trace.go:171","msg":"trace[1180172045] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"106.101235ms","start":"2026-03-18T21:50:10.741936Z","end":"2026-03-18T21:50:10.848037Z","steps":["trace[1180172045] 'process raft request' (duration: 106.035324ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.993414Z","caller":"traceutil/trace.go:171","msg":"trace[1005236746] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"140.127745ms","start":"2026-03-18T21:50:10.853255Z","end":"2026-03-18T21:50:10.993383Z","steps":["trace[1005236746] 'process raft request' (duration: 86.4139ms)","trace[1005236746] 'compare' (duration: 53.399679ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:10.993517Z","caller":"traceutil/trace.go:171","msg":"trace[497480855] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"138.163745ms","start":"2026-03-18T21:50:10.855331Z","end":"2026-03-18T21:50:10.993494Z","steps":["trace[497480855] 'process raft request' (duration: 137.969101ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:10.993704Z","caller":"traceutil/trace.go:171","msg":"trace[1753497549] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1476; }","duration":"139.006122ms","start":"2026-03-18T21:50:10.854649Z","end":"2026-03-18T21:50:10.993655Z","steps":["trace[1753497549] 'read index received' (duration: 24.426383ms)","trace[1753497549] 'applied index is now lower than readState.Index' (duration: 114.570769ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:10.993699Z","caller":"traceutil/trace.go:171","msg":"trace[1504534156] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"110.522085ms","start":"2026-03-18T21:50:10.883136Z","end":"2026-03-18T21:50:10.993658Z","steps":["trace[1504534156] 'process raft request' (duration: 110.25427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:10.994066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.385009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-18T21:50:10.994117Z","caller":"traceutil/trace.go:171","msg":"trace[372382806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1422; }","duration":"137.48057ms","start":"2026-03-18T21:50:10.856625Z","end":"2026-03-18T21:50:10.994105Z","steps":["trace[372382806] 'agreement among raft nodes before linearized reading' (duration: 137.310657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:10.994116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.438981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/pxc-operator\" ","response":"range_response_count:1 size:4010"} {"level":"info","ts":"2026-03-18T21:50:10.994162Z","caller":"traceutil/trace.go:171","msg":"trace[2021816073] range","detail":"{range_begin:/registry/deployments/openstack/pxc-operator; range_end:; response_count:1; response_revision:1422; }","duration":"139.550483ms","start":"2026-03-18T21:50:10.854598Z","end":"2026-03-18T21:50:10.994149Z","steps":["trace[2021816073] 'agreement among raft nodes before linearized reading' (duration: 139.295608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:10.994282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.990455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/pxc-operator-69cb5bbdb9-frxb8\" ","response":"range_response_count:1 size:3255"} {"level":"info","ts":"2026-03-18T21:50:10.994338Z","caller":"traceutil/trace.go:171","msg":"trace[2128288809] range","detail":"{range_begin:/registry/pods/openstack/pxc-operator-69cb5bbdb9-frxb8; range_end:; response_count:1; response_revision:1422; }","duration":"111.089266ms","start":"2026-03-18T21:50:10.883233Z","end":"2026-03-18T21:50:10.994322Z","steps":["trace[2128288809] 'agreement among raft nodes before linearized reading' (duration: 110.858682ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:11.931933Z","caller":"traceutil/trace.go:171","msg":"trace[994084923] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"115.509048ms","start":"2026-03-18T21:50:11.816383Z","end":"2026-03-18T21:50:11.931892Z","steps":["trace[994084923] 'process raft request' (duration: 101.136422ms)","trace[994084923] 'compare' (duration: 14.213692ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:12.110625Z","caller":"traceutil/trace.go:171","msg":"trace[860490742] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"172.046463ms","start":"2026-03-18T21:50:11.938535Z","end":"2026-03-18T21:50:12.110582Z","steps":["trace[860490742] 'process raft request' (duration: 126.185068ms)","trace[860490742] 'compare' (duration: 45.585289ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:12.110682Z","caller":"traceutil/trace.go:171","msg":"trace[1033169564] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"171.159204ms","start":"2026-03-18T21:50:11.939486Z","end":"2026-03-18T21:50:12.110645Z","steps":["trace[1033169564] 'process raft request' (duration: 171.080773ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:12.110848Z","caller":"traceutil/trace.go:171","msg":"trace[96288495] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"168.316786ms","start":"2026-03-18T21:50:11.942495Z","end":"2026-03-18T21:50:12.110812Z","steps":["trace[96288495] 'process raft request' (duration: 168.212943ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:12.110932Z","caller":"traceutil/trace.go:171","msg":"trace[1435771395] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"170.95473ms","start":"2026-03-18T21:50:11.93997Z","end":"2026-03-18T21:50:12.110925Z","steps":["trace[1435771395] 'process raft request' (duration: 170.630243ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:12.111013Z","caller":"traceutil/trace.go:171","msg":"trace[1325135399] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"171.746896ms","start":"2026-03-18T21:50:11.939261Z","end":"2026-03-18T21:50:12.111008Z","steps":["trace[1325135399] 'process raft request' (duration: 171.273446ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:12.11127Z","caller":"traceutil/trace.go:171","msg":"trace[1275355004] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"172.437471ms","start":"2026-03-18T21:50:11.938826Z","end":"2026-03-18T21:50:12.111264Z","steps":["trace[1275355004] 'process raft request' (duration: 171.643695ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:22.528037Z","caller":"traceutil/trace.go:171","msg":"trace[1840543599] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"237.665268ms","start":"2026-03-18T21:50:22.290334Z","end":"2026-03-18T21:50:22.528Z","steps":["trace[1840543599] 'process raft request' (duration: 237.463264ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:22.950258Z","caller":"traceutil/trace.go:171","msg":"trace[1701219125] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"269.776363ms","start":"2026-03-18T21:50:22.680439Z","end":"2026-03-18T21:50:22.950215Z","steps":["trace[1701219125] 'process raft request' (duration: 234.581437ms)","trace[1701219125] 'compare' (duration: 34.9313ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:22.950276Z","caller":"traceutil/trace.go:171","msg":"trace[1126051963] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"269.791893ms","start":"2026-03-18T21:50:22.68044Z","end":"2026-03-18T21:50:22.950232Z","steps":["trace[1126051963] 'process raft request' (duration: 269.66918ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:22.950527Z","caller":"traceutil/trace.go:171","msg":"trace[453608875] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"269.864824ms","start":"2026-03-18T21:50:22.680631Z","end":"2026-03-18T21:50:22.950495Z","steps":["trace[453608875] 'process raft request' (duration: 269.520917ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:39.715331Z","caller":"traceutil/trace.go:171","msg":"trace[1402109013] linearizableReadLoop","detail":"{readStateIndex:1770; appliedIndex:1769; }","duration":"209.773061ms","start":"2026-03-18T21:50:39.505534Z","end":"2026-03-18T21:50:39.715307Z","steps":["trace[1402109013] 'read index received' (duration: 209.664999ms)","trace[1402109013] 'applied index is now lower than readState.Index' (duration: 106.842µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:50:39.715393Z","caller":"traceutil/trace.go:171","msg":"trace[1310930167] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"234.003414ms","start":"2026-03-18T21:50:39.481363Z","end":"2026-03-18T21:50:39.715366Z","steps":["trace[1310930167] 'process raft request' (duration: 233.770129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:50:39.715538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.984825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-18T21:50:39.715583Z","caller":"traceutil/trace.go:171","msg":"trace[721297395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1704; }","duration":"210.045447ms","start":"2026-03-18T21:50:39.505525Z","end":"2026-03-18T21:50:39.715571Z","steps":["trace[721297395] 'agreement among raft nodes before linearized reading' (duration: 209.891144ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:50:39.738525Z","caller":"traceutil/trace.go:171","msg":"trace[1221158723] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"219.299625ms","start":"2026-03-18T21:50:39.519203Z","end":"2026-03-18T21:50:39.738502Z","steps":["trace[1221158723] 'process raft request' (duration: 219.173032ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:51:18.161899Z","caller":"traceutil/trace.go:171","msg":"trace[1352931208] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"156.538067ms","start":"2026-03-18T21:51:18.005331Z","end":"2026-03-18T21:51:18.161869Z","steps":["trace[1352931208] 'process raft request' (duration: 67.389822ms)","trace[1352931208] 'compare' (duration: 88.923181ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:51:18.162019Z","caller":"traceutil/trace.go:171","msg":"trace[1574970527] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"156.458896ms","start":"2026-03-18T21:51:18.005545Z","end":"2026-03-18T21:51:18.162004Z","steps":["trace[1574970527] 'process raft request' (duration: 156.276842ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:51:18.161996Z","caller":"traceutil/trace.go:171","msg":"trace[844167369] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1967; }","duration":"136.770485ms","start":"2026-03-18T21:51:18.025196Z","end":"2026-03-18T21:51:18.161966Z","steps":["trace[844167369] 'read index received' (duration: 47.534148ms)","trace[844167369] 'applied index is now lower than readState.Index' (duration: 89.233857ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:51:18.162251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.00939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T21:51:18.162305Z","caller":"traceutil/trace.go:171","msg":"trace[1234815365] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1896; }","duration":"137.131543ms","start":"2026-03-18T21:51:18.025155Z","end":"2026-03-18T21:51:18.162287Z","steps":["trace[1234815365] 'agreement among raft nodes before linearized reading' (duration: 136.888308ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:52:34.943929Z","caller":"traceutil/trace.go:171","msg":"trace[2017171924] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2561; }","duration":"310.204873ms","start":"2026-03-18T21:52:34.633699Z","end":"2026-03-18T21:52:34.943904Z","steps":["trace[2017171924] 'read index received' (duration: 310.04635ms)","trace[2017171924] 'applied index is now lower than readState.Index' (duration: 157.593µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:52:34.943992Z","caller":"traceutil/trace.go:171","msg":"trace[153244621] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"403.665162ms","start":"2026-03-18T21:52:34.540291Z","end":"2026-03-18T21:52:34.943956Z","steps":["trace[153244621] 'process raft request' (duration: 403.464818ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:52:34.944172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.459508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-03-18T21:52:34.944219Z","caller":"traceutil/trace.go:171","msg":"trace[482510923] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2466; }","duration":"310.543489ms","start":"2026-03-18T21:52:34.633661Z","end":"2026-03-18T21:52:34.944205Z","steps":["trace[482510923] 'agreement among raft nodes before linearized reading' (duration: 310.384676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:52:34.944264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.741071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-18T21:52:34.944369Z","caller":"traceutil/trace.go:171","msg":"trace[2103287845] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2466; }","duration":"159.913325ms","start":"2026-03-18T21:52:34.784435Z","end":"2026-03-18T21:52:34.944349Z","steps":["trace[2103287845] 'agreement among raft nodes before linearized reading' (duration: 159.66032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:52:34.944333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:52:34.633648Z","time spent":"310.672033ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-18T21:52:34.944912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:52:34.540264Z","time spent":"403.819296ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T21:54:59.819384Z","caller":"traceutil/trace.go:171","msg":"trace[204245756] transaction","detail":"{read_only:false; response_revision:3360; number_of_response:1; }","duration":"175.884188ms","start":"2026-03-18T21:54:59.643464Z","end":"2026-03-18T21:54:59.819348Z","steps":["trace[204245756] 'process raft request' (duration: 175.351137ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:54:59.958226Z","caller":"traceutil/trace.go:171","msg":"trace[57011665] transaction","detail":"{read_only:false; response_revision:3361; number_of_response:1; }","duration":"131.25109ms","start":"2026-03-18T21:54:59.826943Z","end":"2026-03-18T21:54:59.958194Z","steps":["trace[57011665] 'process raft request' (duration: 130.990315ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.52609Z","caller":"traceutil/trace.go:171","msg":"trace[537238995] transaction","detail":"{read_only:false; response_revision:4341; number_of_response:1; }","duration":"113.285198ms","start":"2026-03-18T21:56:21.412771Z","end":"2026-03-18T21:56:21.526056Z","steps":["trace[537238995] 'process raft request' (duration: 102.298879ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.526163Z","caller":"traceutil/trace.go:171","msg":"trace[147196429] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"113.018603ms","start":"2026-03-18T21:56:21.413124Z","end":"2026-03-18T21:56:21.526142Z","steps":["trace[147196429] 'process raft request' (duration: 112.87435ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.526168Z","caller":"traceutil/trace.go:171","msg":"trace[2098636818] linearizableReadLoop","detail":"{readStateIndex:4515; appliedIndex:4512; }","duration":"112.394721ms","start":"2026-03-18T21:56:21.413748Z","end":"2026-03-18T21:56:21.526142Z","steps":["trace[2098636818] 'read index received' (duration: 6.688635ms)","trace[2098636818] 'applied index is now lower than readState.Index' (duration: 105.704736ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:21.526248Z","caller":"traceutil/trace.go:171","msg":"trace[1928426624] transaction","detail":"{read_only:false; response_revision:4345; number_of_response:1; }","duration":"112.019044ms","start":"2026-03-18T21:56:21.414213Z","end":"2026-03-18T21:56:21.526232Z","steps":["trace[1928426624] 'process raft request' (duration: 111.88957ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.526236Z","caller":"traceutil/trace.go:171","msg":"trace[173165222] transaction","detail":"{read_only:false; response_revision:4343; number_of_response:1; }","duration":"112.678637ms","start":"2026-03-18T21:56:21.413536Z","end":"2026-03-18T21:56:21.526215Z","steps":["trace[173165222] 'process raft request' (duration: 112.503103ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.526215Z","caller":"traceutil/trace.go:171","msg":"trace[1728371516] transaction","detail":"{read_only:false; response_revision:4344; number_of_response:1; }","duration":"112.117675ms","start":"2026-03-18T21:56:21.414061Z","end":"2026-03-18T21:56:21.526178Z","steps":["trace[1728371516] 'process raft request' (duration: 112.008533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:21.526458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.682477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-03-18T21:56:21.526509Z","caller":"traceutil/trace.go:171","msg":"trace[1581696955] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4345; }","duration":"112.80179ms","start":"2026-03-18T21:56:21.413696Z","end":"2026-03-18T21:56:21.526498Z","steps":["trace[1581696955] 'agreement among raft nodes before linearized reading' (duration: 112.540335ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.574174Z","caller":"traceutil/trace.go:171","msg":"trace[219910409] transaction","detail":"{read_only:false; response_revision:4346; number_of_response:1; }","duration":"148.81137ms","start":"2026-03-18T21:56:21.425328Z","end":"2026-03-18T21:56:21.574139Z","steps":["trace[219910409] 'process raft request' (duration: 148.619297ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.704673Z","caller":"traceutil/trace.go:171","msg":"trace[1583024590] transaction","detail":"{read_only:false; response_revision:4349; number_of_response:1; }","duration":"125.888061ms","start":"2026-03-18T21:56:21.578724Z","end":"2026-03-18T21:56:21.704612Z","steps":["trace[1583024590] 'process raft request' (duration: 125.515283ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.704774Z","caller":"traceutil/trace.go:171","msg":"trace[710236459] transaction","detail":"{read_only:false; response_revision:4350; number_of_response:1; }","duration":"123.868ms","start":"2026-03-18T21:56:21.580868Z","end":"2026-03-18T21:56:21.704736Z","steps":["trace[710236459] 'process raft request' (duration: 123.605965ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.704839Z","caller":"traceutil/trace.go:171","msg":"trace[1782736321] linearizableReadLoop","detail":"{readStateIndex:4522; appliedIndex:4519; }","duration":"119.366099ms","start":"2026-03-18T21:56:21.585433Z","end":"2026-03-18T21:56:21.7048Z","steps":["trace[1782736321] 'read index received' (duration: 37.659863ms)","trace[1782736321] 'applied index is now lower than readState.Index' (duration: 81.704396ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:21.704977Z","caller":"traceutil/trace.go:171","msg":"trace[22841993] transaction","detail":"{read_only:false; response_revision:4352; number_of_response:1; }","duration":"116.411621ms","start":"2026-03-18T21:56:21.588553Z","end":"2026-03-18T21:56:21.704965Z","steps":["trace[22841993] 'process raft request' (duration: 116.133845ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:21.705254Z","caller":"traceutil/trace.go:171","msg":"trace[1744970864] transaction","detail":"{read_only:false; response_revision:4351; number_of_response:1; }","duration":"118.422771ms","start":"2026-03-18T21:56:21.586777Z","end":"2026-03-18T21:56:21.7052Z","steps":["trace[1744970864] 'process raft request' (duration: 117.798309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:21.705279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.811419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14494"} {"level":"info","ts":"2026-03-18T21:56:21.705358Z","caller":"traceutil/trace.go:171","msg":"trace[233799773] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4352; }","duration":"119.966602ms","start":"2026-03-18T21:56:21.585366Z","end":"2026-03-18T21:56:21.705332Z","steps":["trace[233799773] 'agreement among raft nodes before linearized reading' (duration: 119.629815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:21.706476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.190536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196029"} {"level":"info","ts":"2026-03-18T21:56:21.706542Z","caller":"traceutil/trace.go:171","msg":"trace[57191036] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4352; }","duration":"112.316999ms","start":"2026-03-18T21:56:21.594207Z","end":"2026-03-18T21:56:21.706524Z","steps":["trace[57191036] 'agreement among raft nodes before linearized reading' (duration: 110.825619ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:26.280112Z","caller":"traceutil/trace.go:171","msg":"trace[1510162197] linearizableReadLoop","detail":"{readStateIndex:4670; appliedIndex:4669; }","duration":"115.240078ms","start":"2026-03-18T21:56:26.164845Z","end":"2026-03-18T21:56:26.280085Z","steps":["trace[1510162197] 'read index received' (duration: 115.023354ms)","trace[1510162197] 'applied index is now lower than readState.Index' (duration: 215.094µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:26.280261Z","caller":"traceutil/trace.go:171","msg":"trace[1368815966] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"283.933916ms","start":"2026-03-18T21:56:25.996274Z","end":"2026-03-18T21:56:26.280207Z","steps":["trace[1368815966] 'process raft request' (duration: 283.603409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:26.28043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.574165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-5q7dt-tls\" ","response":"range_response_count:1 size:2182"} {"level":"info","ts":"2026-03-18T21:56:26.28053Z","caller":"traceutil/trace.go:171","msg":"trace[1683742245] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-5q7dt-tls; range_end:; response_count:1; response_revision:4493; }","duration":"115.735448ms","start":"2026-03-18T21:56:26.164771Z","end":"2026-03-18T21:56:26.280506Z","steps":["trace[1683742245] 'agreement among raft nodes before linearized reading' (duration: 115.450242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:26.280547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.849219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c65575fd-3747-45b5-981f-43594d96bf39\" ","response":"range_response_count:1 size:3599"} {"level":"info","ts":"2026-03-18T21:56:26.280591Z","caller":"traceutil/trace.go:171","msg":"trace[464853326] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c65575fd-3747-45b5-981f-43594d96bf39; range_end:; response_count:1; response_revision:4493; }","duration":"100.931681ms","start":"2026-03-18T21:56:26.179647Z","end":"2026-03-18T21:56:26.280579Z","steps":["trace[464853326] 'agreement among raft nodes before linearized reading' (duration: 100.593215ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:26.666447Z","caller":"traceutil/trace.go:171","msg":"trace[1847655451] linearizableReadLoop","detail":"{readStateIndex:4686; appliedIndex:4685; }","duration":"106.345719ms","start":"2026-03-18T21:56:26.560062Z","end":"2026-03-18T21:56:26.666407Z","steps":["trace[1847655451] 'read index received' (duration: 67.43781ms)","trace[1847655451] 'applied index is now lower than readState.Index' (duration: 38.906629ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:26.666478Z","caller":"traceutil/trace.go:171","msg":"trace[1323448525] transaction","detail":"{read_only:false; response_revision:4509; number_of_response:1; }","duration":"161.681517ms","start":"2026-03-18T21:56:26.50475Z","end":"2026-03-18T21:56:26.666432Z","steps":["trace[1323448525] 'process raft request' (duration: 122.713116ms)","trace[1323448525] 'compare' (duration: 38.727976ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:56:26.667126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.044435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777423"} {"level":"info","ts":"2026-03-18T21:56:26.667211Z","caller":"traceutil/trace.go:171","msg":"trace[1848249401] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4509; }","duration":"107.192268ms","start":"2026-03-18T21:56:26.559998Z","end":"2026-03-18T21:56:26.66719Z","steps":["trace[1848249401] 'agreement among raft nodes before linearized reading' (duration: 106.536494ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:44.568437Z","caller":"traceutil/trace.go:171","msg":"trace[762248870] linearizableReadLoop","detail":"{readStateIndex:5067; appliedIndex:5066; }","duration":"188.816551ms","start":"2026-03-18T21:56:44.379593Z","end":"2026-03-18T21:56:44.56841Z","steps":["trace[762248870] 'read index received' (duration: 142.681017ms)","trace[762248870] 'applied index is now lower than readState.Index' (duration: 46.134214ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:44.568578Z","caller":"traceutil/trace.go:171","msg":"trace[924441312] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"190.634927ms","start":"2026-03-18T21:56:44.37793Z","end":"2026-03-18T21:56:44.568565Z","steps":["trace[924441312] 'process raft request' (duration: 144.422722ms)","trace[924441312] 'compare' (duration: 45.901049ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:56:44.56903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.851631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110596"} {"level":"warn","ts":"2026-03-18T21:56:44.569043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.417913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-03-18T21:56:44.569041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.86069ms","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-03-18T21:56:44.5691Z","caller":"traceutil/trace.go:171","msg":"trace[276614639] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:4879; }","duration":"188.953683ms","start":"2026-03-18T21:56:44.380131Z","end":"2026-03-18T21:56:44.569085Z","steps":["trace[276614639] 'agreement among raft nodes before linearized reading' (duration: 188.583935ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:44.569127Z","caller":"traceutil/trace.go:171","msg":"trace[375471861] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4879; }","duration":"189.551346ms","start":"2026-03-18T21:56:44.379552Z","end":"2026-03-18T21:56:44.569104Z","steps":["trace[375471861] 'agreement among raft nodes before linearized reading' (duration: 189.26254ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:44.569143Z","caller":"traceutil/trace.go:171","msg":"trace[1027845399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4879; }","duration":"150.013803ms","start":"2026-03-18T21:56:44.419103Z","end":"2026-03-18T21:56:44.569117Z","steps":["trace[1027845399] 'agreement among raft nodes before linearized reading' (duration: 149.748968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:44.569558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.3632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bfd7ad92-45bf-4c24-a3fd-9e94c2fece44\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-03-18T21:56:44.569863Z","caller":"traceutil/trace.go:171","msg":"trace[1607646577] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bfd7ad92-45bf-4c24-a3fd-9e94c2fece44; range_end:; response_count:1; response_revision:4879; }","duration":"111.721437ms","start":"2026-03-18T21:56:44.458118Z","end":"2026-03-18T21:56:44.569839Z","steps":["trace[1607646577] 'agreement among raft nodes before linearized reading' (duration: 110.667726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:44.804186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.410861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T21:56:44.804524Z","caller":"traceutil/trace.go:171","msg":"trace[2146119693] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"224.600117ms","start":"2026-03-18T21:56:44.579881Z","end":"2026-03-18T21:56:44.804481Z","steps":["trace[2146119693] 'process raft request' (duration: 224.404493ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:44.804563Z","caller":"traceutil/trace.go:171","msg":"trace[905808562] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"230.576187ms","start":"2026-03-18T21:56:44.573938Z","end":"2026-03-18T21:56:44.804514Z","steps":["trace[905808562] 'process raft request' (duration: 109.608775ms)","trace[905808562] 'compare' (duration: 120.303409ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:44.804543Z","caller":"traceutil/trace.go:171","msg":"trace[1431126006] linearizableReadLoop","detail":"{readStateIndex:5069; appliedIndex:5067; }","duration":"229.038987ms","start":"2026-03-18T21:56:44.575479Z","end":"2026-03-18T21:56:44.804518Z","steps":["trace[1431126006] 'read index received' (duration: 15.107704ms)","trace[1431126006] 'applied index is now lower than readState.Index' (duration: 213.927803ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T21:56:44.877086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.57208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-03-18T21:56:44.877231Z","caller":"traceutil/trace.go:171","msg":"trace[1923969274] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4882; }","duration":"301.751503ms","start":"2026-03-18T21:56:44.57545Z","end":"2026-03-18T21:56:44.877202Z","steps":["trace[1923969274] 'agreement among raft nodes before linearized reading' (duration: 229.123938ms)","trace[1923969274] 'range keys from in-memory index tree' (duration: 72.280598ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:44.877215Z","caller":"traceutil/trace.go:171","msg":"trace[636774383] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"204.983725ms","start":"2026-03-18T21:56:44.672169Z","end":"2026-03-18T21:56:44.877153Z","steps":["trace[636774383] 'process raft request' (duration: 204.77057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:44.877317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.070788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4865"} {"level":"warn","ts":"2026-03-18T21:56:44.877358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T21:56:44.575433Z","time spent":"301.868605ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":2666,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-03-18T21:56:44.877255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.449904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-03-18T21:56:44.877526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.032597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-03-18T21:56:44.8776Z","caller":"traceutil/trace.go:171","msg":"trace[2071945866] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4883; }","duration":"203.767651ms","start":"2026-03-18T21:56:44.673736Z","end":"2026-03-18T21:56:44.877503Z","steps":["trace[2071945866] 'agreement among raft nodes before linearized reading' (duration: 203.400713ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:44.8779Z","caller":"traceutil/trace.go:171","msg":"trace[1783287399] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4883; }","duration":"281.234852ms","start":"2026-03-18T21:56:44.596426Z","end":"2026-03-18T21:56:44.877661Z","steps":["trace[1783287399] 'agreement among raft nodes before linearized reading' (duration: 280.737532ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:44.8774Z","caller":"traceutil/trace.go:171","msg":"trace[1932818455] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4883; }","duration":"294.192271ms","start":"2026-03-18T21:56:44.583189Z","end":"2026-03-18T21:56:44.877381Z","steps":["trace[1932818455] 'agreement among raft nodes before linearized reading' (duration: 293.961437ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:45.023121Z","caller":"traceutil/trace.go:171","msg":"trace[326277085] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"137.657506ms","start":"2026-03-18T21:56:44.885436Z","end":"2026-03-18T21:56:45.023093Z","steps":["trace[326277085] 'process raft request' (duration: 137.468412ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:56:45.038495Z","caller":"traceutil/trace.go:171","msg":"trace[160976078] linearizableReadLoop","detail":"{readStateIndex:5074; appliedIndex:5072; }","duration":"149.928412ms","start":"2026-03-18T21:56:44.88855Z","end":"2026-03-18T21:56:45.038478Z","steps":["trace[160976078] 'read index received' (duration: 134.464592ms)","trace[160976078] 'applied index is now lower than readState.Index' (duration: 15.46257ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T21:56:45.038607Z","caller":"traceutil/trace.go:171","msg":"trace[417281588] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"152.8287ms","start":"2026-03-18T21:56:44.885758Z","end":"2026-03-18T21:56:45.038587Z","steps":["trace[417281588] 'process raft request' (duration: 152.604076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:45.038717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.154807ms","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-03-18T21:56:45.038763Z","caller":"traceutil/trace.go:171","msg":"trace[501416070] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4885; }","duration":"150.256419ms","start":"2026-03-18T21:56:44.888491Z","end":"2026-03-18T21:56:45.038748Z","steps":["trace[501416070] 'agreement among raft nodes before linearized reading' (duration: 150.076515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T21:56:45.050786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.244038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3565"} {"level":"info","ts":"2026-03-18T21:56:45.050861Z","caller":"traceutil/trace.go:171","msg":"trace[462821451] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4891; }","duration":"145.36168ms","start":"2026-03-18T21:56:44.90548Z","end":"2026-03-18T21:56:45.050841Z","steps":["trace[462821451] 'agreement among raft nodes before linearized reading' (duration: 145.165187ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T21:58:26.150504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2721} {"level":"info","ts":"2026-03-18T21:58:26.204655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2721,"took":"53.009319ms","hash":1049973009,"current-db-size-bytes":41697280,"current-db-size":"42 MB","current-db-size-in-use-bytes":31289344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-18T21:58:26.20472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1049973009,"revision":2721,"compact-revision":-1} {"level":"warn","ts":"2026-03-18T22:02:36.449857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.78095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T22:02:36.449907Z","caller":"traceutil/trace.go:171","msg":"trace[832776500] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:7466; }","duration":"113.871972ms","start":"2026-03-18T22:02:36.336027Z","end":"2026-03-18T22:02:36.449899Z","steps":["trace[832776500] 'range keys from in-memory index tree' (duration: 113.698048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T22:02:36.449925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.711978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T22:02:36.449936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.729159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T22:02:36.450006Z","caller":"traceutil/trace.go:171","msg":"trace[736713344] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:7466; }","duration":"114.83025ms","start":"2026-03-18T22:02:36.33516Z","end":"2026-03-18T22:02:36.44999Z","steps":["trace[736713344] 'range keys from in-memory index tree' (duration: 114.654277ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T22:02:36.450006Z","caller":"traceutil/trace.go:171","msg":"trace[948661867] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:7466; }","duration":"114.81636ms","start":"2026-03-18T22:02:36.335167Z","end":"2026-03-18T22:02:36.449984Z","steps":["trace[948661867] 'count revisions from in-memory index tree' (duration: 114.637427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T22:02:48.09638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.38391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-03-18T22:02:48.096471Z","caller":"traceutil/trace.go:171","msg":"trace[1922467183] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7521; }","duration":"134.486612ms","start":"2026-03-18T22:02:47.961965Z","end":"2026-03-18T22:02:48.096452Z","steps":["trace[1922467183] 'range keys from in-memory index tree' (duration: 134.198117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T22:02:48.096534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.86749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zhpck\" ","response":"range_response_count:1 size:6523"} {"level":"info","ts":"2026-03-18T22:02:48.096601Z","caller":"traceutil/trace.go:171","msg":"trace[313212118] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zhpck; range_end:; response_count:1; response_revision:7521; }","duration":"124.198207ms","start":"2026-03-18T22:02:47.972389Z","end":"2026-03-18T22:02:48.096587Z","steps":["trace[313212118] 'range keys from in-memory index tree' (duration: 123.694577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T22:02:48.890253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.717384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T22:02:48.890343Z","caller":"traceutil/trace.go:171","msg":"trace[1503358100] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:7526; }","duration":"207.853076ms","start":"2026-03-18T22:02:48.682471Z","end":"2026-03-18T22:02:48.890324Z","steps":["trace[1503358100] 'count revisions from in-memory index tree' (duration: 207.634621ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T22:03:26.159345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5736} {"level":"info","ts":"2026-03-18T22:03:26.313019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5736,"took":"151.674275ms","hash":1312238712,"current-db-size-bytes":43294720,"current-db-size":"43 MB","current-db-size-in-use-bytes":23199744,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-18T22:03:26.313103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1312238712,"revision":5736,"compact-revision":2721} {"level":"info","ts":"2026-03-18T22:03:28.689993Z","caller":"traceutil/trace.go:171","msg":"trace[928479088] transaction","detail":"{read_only:false; response_revision:7815; number_of_response:1; }","duration":"186.167425ms","start":"2026-03-18T22:03:28.503798Z","end":"2026-03-18T22:03:28.689966Z","steps":["trace[928479088] 'process raft request' (duration: 186.030552ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T22:03:29.543444Z","caller":"traceutil/trace.go:171","msg":"trace[117223671] transaction","detail":"{read_only:false; response_revision:7824; number_of_response:1; }","duration":"165.528971ms","start":"2026-03-18T22:03:29.37789Z","end":"2026-03-18T22:03:29.543419Z","steps":["trace[117223671] 'process raft request' (duration: 165.350458ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T22:07:01.9699Z","caller":"traceutil/trace.go:171","msg":"trace[1446462788] linearizableReadLoop","detail":"{readStateIndex:9846; appliedIndex:9845; }","duration":"100.901909ms","start":"2026-03-18T22:07:01.868969Z","end":"2026-03-18T22:07:01.969871Z","steps":["trace[1446462788] 'read index received' (duration: 100.648544ms)","trace[1446462788] 'applied index is now lower than readState.Index' (duration: 252.205µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T22:07:01.969957Z","caller":"traceutil/trace.go:171","msg":"trace[77477036] transaction","detail":"{read_only:false; response_revision:9501; number_of_response:1; }","duration":"115.57462ms","start":"2026-03-18T22:07:01.85434Z","end":"2026-03-18T22:07:01.969915Z","steps":["trace[77477036] 'process raft request' (duration: 115.287565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T22:07:01.970207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.221785ms","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-03-18T22:07:01.970266Z","caller":"traceutil/trace.go:171","msg":"trace[1179377952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9501; }","duration":"101.301756ms","start":"2026-03-18T22:07:01.868952Z","end":"2026-03-18T22:07:01.970254Z","steps":["trace[1179377952] 'agreement among raft nodes before linearized reading' (duration: 101.102222ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T22:07:24.79083Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"66f7b57d56ad567d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T22:07:24.797562Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-18T22:07:24.797642Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-18T22:08:26.16611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7790} {"level":"info","ts":"2026-03-18T22:08:26.230344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7790,"took":"62.238249ms","hash":401333765,"current-db-size-bytes":43294720,"current-db-size":"43 MB","current-db-size-in-use-bytes":25538560,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-18T22:08:26.230426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":401333765,"revision":7790,"compact-revision":5736} {"level":"info","ts":"2026-03-18T22:13:26.173678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9953} {"level":"info","ts":"2026-03-18T22:13:26.249203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9953,"took":"73.119874ms","hash":3398641330,"current-db-size-bytes":43294720,"current-db-size":"43 MB","current-db-size-in-use-bytes":16596992,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-18T22:13:26.249258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3398641330,"revision":9953,"compact-revision":7790}