{"level":"warn","ts":"2026-03-13T23:02:33.469984Z","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-13T23:02:33.470054Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-13T23:02:33.470113Z","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-13T23:02:33.470124Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]}
{"level":"info","ts":"2026-03-13T23:02:33.470141Z","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-13T23:02:33.470772Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]}
{"level":"info","ts":"2026-03-13T23:02:33.470841Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-13T23:02:33.474528Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.524535ms"}
{"level":"info","ts":"2026-03-13T23:02:33.479827Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"}
{"level":"info","ts":"2026-03-13T23:02:33.479912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-13T23:02:33.47994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"}
{"level":"info","ts":"2026-03-13T23:02:33.479952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-13T23:02:33.479961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"}
{"level":"info","ts":"2026-03-13T23:02:33.479997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"}
{"level":"warn","ts":"2026-03-13T23:02:33.488564Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-13T23:02:33.490287Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-13T23:02:33.491612Z","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-13T23:02:33.495586Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-13T23:02:33.496349Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-13T23:02:33.496381Z","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-13T23:02:33.496655Z","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-13T23:02:33.496712Z","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-13T23:02:33.499086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"}
{"level":"info","ts":"2026-03-13T23:02:33.499214Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]}
{"level":"info","ts":"2026-03-13T23:02:33.501119Z","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-13T23:02:33.501259Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"}
{"level":"info","ts":"2026-03-13T23:02:33.501289Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"}
{"level":"info","ts":"2026-03-13T23:02:33.501522Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-13T23:02:33.501608Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-13T23:02:33.880439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-13T23:02:33.880549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-13T23:02:33.880591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"}
{"level":"info","ts":"2026-03-13T23:02:33.88062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"}
{"level":"info","ts":"2026-03-13T23:02:33.880657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"}
{"level":"info","ts":"2026-03-13T23:02:33.880683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"}
{"level":"info","ts":"2026-03-13T23:02:33.880702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"}
{"level":"info","ts":"2026-03-13T23:02:33.89519Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-13T23:02:33.901389Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-13T23:02:33.901407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-13T23:02:33.901443Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-13T23:02:33.901682Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-13T23:02:33.901745Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-13T23:02:33.903105Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-13T23:02:33.903292Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-13T23:02:33.903405Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-13T23:02:33.904782Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"}
{"level":"info","ts":"2026-03-13T23:02:33.905494Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-13T23:02:55.346901Z","caller":"traceutil/trace.go:171","msg":"trace[793683661] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"130.645449ms","start":"2026-03-13T23:02:55.216233Z","end":"2026-03-13T23:02:55.346879Z","steps":["trace[793683661] 'process raft request'  (duration: 130.526036ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:02:55.523873Z","caller":"traceutil/trace.go:171","msg":"trace[1615704797] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"123.807172ms","start":"2026-03-13T23:02:55.400035Z","end":"2026-03-13T23:02:55.523842Z","steps":["trace[1615704797] 'read index received'  (duration: 122.942132ms)","trace[1615704797] 'applied index is now lower than readState.Index'  (duration: 863.48µs)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:02:55.523923Z","caller":"traceutil/trace.go:171","msg":"trace[1821764376] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"143.473543ms","start":"2026-03-13T23:02:55.38041Z","end":"2026-03-13T23:02:55.523884Z","steps":["trace[1821764376] 'process raft request'  (duration: 142.578884ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:02:55.524073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.978196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-13T23:02:55.524186Z","caller":"traceutil/trace.go:171","msg":"trace[629377397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"124.156649ms","start":"2026-03-13T23:02:55.40001Z","end":"2026-03-13T23:02:55.524166Z","steps":["trace[629377397] 'agreement among raft nodes before linearized reading'  (duration: 123.947215ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:03:07.704272Z","caller":"traceutil/trace.go:171","msg":"trace[1247877722] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"122.691278ms","start":"2026-03-13T23:03:07.581553Z","end":"2026-03-13T23:03:07.704244Z","steps":["trace[1247877722] 'process raft request'  (duration: 122.530775ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:03:07.83136Z","caller":"traceutil/trace.go:171","msg":"trace[255822787] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"205.551643ms","start":"2026-03-13T23:03:07.625782Z","end":"2026-03-13T23:03:07.831334Z","steps":["trace[255822787] 'read index received'  (duration: 78.285047ms)","trace[255822787] 'applied index is now lower than readState.Index'  (duration: 127.265436ms)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:03:07.831449Z","caller":"traceutil/trace.go:171","msg":"trace[887537557] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"247.684955ms","start":"2026-03-13T23:03:07.583737Z","end":"2026-03-13T23:03:07.831422Z","steps":["trace[887537557] 'process raft request'  (duration: 236.455525ms)","trace[887537557] 'compare'  (duration: 11.031806ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-13T23:03:07.831549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.749067ms","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-13T23:03:07.831591Z","caller":"traceutil/trace.go:171","msg":"trace[1118237767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:500; }","duration":"205.809188ms","start":"2026-03-13T23:03:07.62577Z","end":"2026-03-13T23:03:07.831579Z","steps":["trace[1118237767] 'agreement among raft nodes before linearized reading'  (duration: 205.659455ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:04:22.56412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.825393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-13T23:04:22.564273Z","caller":"traceutil/trace.go:171","msg":"trace[24438419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1554; }","duration":"164.031737ms","start":"2026-03-13T23:04:22.400212Z","end":"2026-03-13T23:04:22.564244Z","steps":["trace[24438419] 'range keys from in-memory index tree'  (duration: 163.748771ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:04:22.564321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.348218ms","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-03-13T23:04:22.564606Z","caller":"traceutil/trace.go:171","msg":"trace[1381322370] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1554; }","duration":"131.495181ms","start":"2026-03-13T23:04:22.432904Z","end":"2026-03-13T23:04:22.564399Z","steps":["trace[1381322370] 'range keys from in-memory index tree'  (duration: 131.178994ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:04:22.564702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.111685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-41d4f857-e8c6-46f5-bf6a-076740641e11\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-03-13T23:04:22.564885Z","caller":"traceutil/trace.go:171","msg":"trace[1462203037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-41d4f857-e8c6-46f5-bf6a-076740641e11; range_end:; response_count:1; response_revision:1554; }","duration":"226.428752ms","start":"2026-03-13T23:04:22.338433Z","end":"2026-03-13T23:04:22.564862Z","steps":["trace[1462203037] 'range keys from in-memory index tree'  (duration: 225.8868ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:04:30.710482Z","caller":"traceutil/trace.go:171","msg":"trace[264500436] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"126.634248ms","start":"2026-03-13T23:04:30.583827Z","end":"2026-03-13T23:04:30.710462Z","steps":["trace[264500436] 'process raft request'  (duration: 123.476391ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:04:30.712723Z","caller":"traceutil/trace.go:171","msg":"trace[1428220429] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"124.92584ms","start":"2026-03-13T23:04:30.587781Z","end":"2026-03-13T23:04:30.712707Z","steps":["trace[1428220429] 'process raft request'  (duration: 124.786897ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:04:30.712681Z","caller":"traceutil/trace.go:171","msg":"trace[239508568] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"125.611936ms","start":"2026-03-13T23:04:30.587038Z","end":"2026-03-13T23:04:30.71265Z","steps":["trace[239508568] 'process raft request'  (duration: 125.418382ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:04:30.712903Z","caller":"traceutil/trace.go:171","msg":"trace[725476175] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"124.446291ms","start":"2026-03-13T23:04:30.588447Z","end":"2026-03-13T23:04:30.712893Z","steps":["trace[725476175] 'process raft request'  (duration: 124.166765ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:05:06.67658Z","caller":"traceutil/trace.go:171","msg":"trace[1569614272] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"123.958278ms","start":"2026-03-13T23:05:06.552591Z","end":"2026-03-13T23:05:06.676549Z","steps":["trace[1569614272] 'process raft request'  (duration: 123.769304ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:05:06.691617Z","caller":"traceutil/trace.go:171","msg":"trace[1439802409] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"138.036798ms","start":"2026-03-13T23:05:06.553547Z","end":"2026-03-13T23:05:06.691584Z","steps":["trace[1439802409] 'process raft request'  (duration: 137.872505ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:05:06.691848Z","caller":"traceutil/trace.go:171","msg":"trace[219703042] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"136.574938ms","start":"2026-03-13T23:05:06.555231Z","end":"2026-03-13T23:05:06.691806Z","steps":["trace[219703042] 'process raft request'  (duration: 136.311901ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:05:56.215759Z","caller":"traceutil/trace.go:171","msg":"trace[23517216] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"199.924865ms","start":"2026-03-13T23:05:56.015815Z","end":"2026-03-13T23:05:56.21574Z","steps":["trace[23517216] 'process raft request'  (duration: 199.583318ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:09:11.536841Z","caller":"traceutil/trace.go:171","msg":"trace[1059758840] transaction","detail":"{read_only:false; response_revision:3244; number_of_response:1; }","duration":"124.016325ms","start":"2026-03-13T23:09:11.412799Z","end":"2026-03-13T23:09:11.536815Z","steps":["trace[1059758840] 'process raft request'  (duration: 123.853872ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:09:11.555103Z","caller":"traceutil/trace.go:171","msg":"trace[559541953] transaction","detail":"{read_only:false; response_revision:3245; number_of_response:1; }","duration":"139.778522ms","start":"2026-03-13T23:09:11.415306Z","end":"2026-03-13T23:09:11.555084Z","steps":["trace[559541953] 'process raft request'  (duration: 139.68505ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:40.962607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.160208ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12737478172529996122 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:4358 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-13T23:10:40.962757Z","caller":"traceutil/trace.go:171","msg":"trace[1167541649] linearizableReadLoop","detail":"{readStateIndex:4581; appliedIndex:4580; }","duration":"250.299008ms","start":"2026-03-13T23:10:40.712427Z","end":"2026-03-13T23:10:40.962726Z","steps":["trace[1167541649] 'read index received'  (duration: 71.264ms)","trace[1167541649] 'applied index is now lower than readState.Index'  (duration: 179.032998ms)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:10:40.962767Z","caller":"traceutil/trace.go:171","msg":"trace[1019277881] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"282.714778ms","start":"2026-03-13T23:10:40.68002Z","end":"2026-03-13T23:10:40.962734Z","steps":["trace[1019277881] 'process raft request'  (duration: 103.704591ms)","trace[1019277881] 'compare'  (duration: 178.022285ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-13T23:10:40.96296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.540342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-ftlr8-tls\" ","response":"range_response_count:1 size:1967"}
{"level":"info","ts":"2026-03-13T23:10:40.963002Z","caller":"traceutil/trace.go:171","msg":"trace[1972239606] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-ftlr8-tls; range_end:; response_count:1; response_revision:4401; }","duration":"250.632364ms","start":"2026-03-13T23:10:40.712357Z","end":"2026-03-13T23:10:40.962989Z","steps":["trace[1972239606] 'agreement among raft nodes before linearized reading'  (duration: 250.498891ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:40.963169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.723367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-38806899-042f-4012-9dc1-14a2934a1d95\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-03-13T23:10:40.963207Z","caller":"traceutil/trace.go:171","msg":"trace[1898559493] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-38806899-042f-4012-9dc1-14a2934a1d95; range_end:; response_count:1; response_revision:4401; }","duration":"115.772029ms","start":"2026-03-13T23:10:40.847424Z","end":"2026-03-13T23:10:40.963196Z","steps":["trace[1898559493] 'agreement among raft nodes before linearized reading'  (duration: 115.644356ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:10:42.725535Z","caller":"traceutil/trace.go:171","msg":"trace[477996173] linearizableReadLoop","detail":"{readStateIndex:4627; appliedIndex:4626; }","duration":"137.919151ms","start":"2026-03-13T23:10:42.587582Z","end":"2026-03-13T23:10:42.725501Z","steps":["trace[477996173] 'read index received'  (duration: 93.702µs)","trace[477996173] 'applied index is now lower than readState.Index'  (duration: 137.823899ms)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:10:42.725696Z","caller":"traceutil/trace.go:171","msg":"trace[1299107568] transaction","detail":"{read_only:false; response_revision:4445; number_of_response:1; }","duration":"138.119205ms","start":"2026-03-13T23:10:42.587538Z","end":"2026-03-13T23:10:42.725657Z","steps":["trace[1299107568] 'process raft request'  (duration: 137.725357ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:42.725828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.241927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16648"}
{"level":"info","ts":"2026-03-13T23:10:42.725897Z","caller":"traceutil/trace.go:171","msg":"trace[1613850019] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4445; }","duration":"138.36198ms","start":"2026-03-13T23:10:42.587517Z","end":"2026-03-13T23:10:42.725879Z","steps":["trace[1613850019] 'agreement among raft nodes before linearized reading'  (duration: 138.119575ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:10:42.726088Z","caller":"traceutil/trace.go:171","msg":"trace[1220744923] transaction","detail":"{read_only:false; response_revision:4447; number_of_response:1; }","duration":"137.935811ms","start":"2026-03-13T23:10:42.588123Z","end":"2026-03-13T23:10:42.726059Z","steps":["trace[1220744923] 'process raft request'  (duration: 137.88601ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:10:42.726197Z","caller":"traceutil/trace.go:171","msg":"trace[1158464322] transaction","detail":"{read_only:false; response_revision:4446; number_of_response:1; }","duration":"138.524743ms","start":"2026-03-13T23:10:42.587649Z","end":"2026-03-13T23:10:42.726174Z","steps":["trace[1158464322] 'process raft request'  (duration: 138.243517ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:10:58.339882Z","caller":"traceutil/trace.go:171","msg":"trace[1542055896] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"110.898095ms","start":"2026-03-13T23:10:58.22896Z","end":"2026-03-13T23:10:58.339858Z","steps":["trace[1542055896] 'process raft request'  (duration: 110.759503ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:58.901687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478172529996873,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-13T23:10:58.933114Z","caller":"traceutil/trace.go:171","msg":"trace[680502197] linearizableReadLoop","detail":"{readStateIndex:4923; appliedIndex:4922; }","duration":"532.458384ms","start":"2026-03-13T23:10:58.400633Z","end":"2026-03-13T23:10:58.933091Z","steps":["trace[680502197] 'read index received'  (duration: 532.239589ms)","trace[680502197] 'applied index is now lower than readState.Index'  (duration: 218.075µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-13T23:10:58.93333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.656809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-13T23:10:58.93343Z","caller":"traceutil/trace.go:171","msg":"trace[69056788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4735; }","duration":"532.810472ms","start":"2026-03-13T23:10:58.40059Z","end":"2026-03-13T23:10:58.933401Z","steps":["trace[69056788] 'agreement among raft nodes before linearized reading'  (duration: 532.630608ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:58.933474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:58.400577Z","time spent":"532.888224ms","remote":"127.0.0.1:59340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-13T23:10:58.933599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.78544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-03-13T23:10:58.933636Z","caller":"traceutil/trace.go:171","msg":"trace[333987417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4735; }","duration":"131.854141ms","start":"2026-03-13T23:10:58.801767Z","end":"2026-03-13T23:10:58.933621Z","steps":["trace[333987417] 'agreement among raft nodes before linearized reading'  (duration: 131.77488ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:58.933624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.670328ms","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-03-13T23:10:58.933661Z","caller":"traceutil/trace.go:171","msg":"trace[1695988766] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"563.129908ms","start":"2026-03-13T23:10:58.370467Z","end":"2026-03-13T23:10:58.933597Z","steps":["trace[1695988766] 'process raft request'  (duration: 562.482603ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:58.933513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.855249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189c896da85a690a\" ","response":"range_response_count:1 size:1012"}
{"level":"warn","ts":"2026-03-13T23:10:58.933898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.454443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"warn","ts":"2026-03-13T23:10:58.933894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:58.370445Z","time spent":"563.333972ms","remote":"127.0.0.1:59522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7aa328ba-87b7-4809-802b-e82335399462\" mod_revision:4715 > success:<request_put:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7aa328ba-87b7-4809-802b-e82335399462\" value_size:3651 >> failure:<request_range:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7aa328ba-87b7-4809-802b-e82335399462\" > >"}
{"level":"info","ts":"2026-03-13T23:10:58.933711Z","caller":"traceutil/trace.go:171","msg":"trace[589439040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4735; }","duration":"131.820081ms","start":"2026-03-13T23:10:58.801868Z","end":"2026-03-13T23:10:58.933688Z","steps":["trace[589439040] 'agreement among raft nodes before linearized reading'  (duration: 131.541855ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:10:58.933982Z","caller":"traceutil/trace.go:171","msg":"trace[1255294187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4735; }","duration":"131.560785ms","start":"2026-03-13T23:10:58.802399Z","end":"2026-03-13T23:10:58.93396Z","steps":["trace[1255294187] 'agreement among raft nodes before linearized reading'  (duration: 131.282079ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:10:58.933959Z","caller":"traceutil/trace.go:171","msg":"trace[1623599361] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189c896da85a690a; range_end:; response_count:1; response_revision:4735; }","duration":"402.312069ms","start":"2026-03-13T23:10:58.531583Z","end":"2026-03-13T23:10:58.933895Z","steps":["trace[1623599361] 'agreement among raft nodes before linearized reading'  (duration: 401.796198ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:10:58.934148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:58.531562Z","time spent":"402.515463ms","remote":"127.0.0.1:59420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1036,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.189c896da85a690a\" "}
{"level":"info","ts":"2026-03-13T23:12:33.933963Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2592}
{"level":"info","ts":"2026-03-13T23:12:33.98627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2592,"took":"50.849083ms","hash":3744056359,"current-db-size-bytes":40894464,"current-db-size":"41 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-13T23:12:33.986348Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3744056359,"revision":2592,"compact-revision":-1}
{"level":"info","ts":"2026-03-13T23:16:49.295512Z","caller":"traceutil/trace.go:171","msg":"trace[146126229] transaction","detail":"{read_only:false; response_revision:7347; number_of_response:1; }","duration":"255.969326ms","start":"2026-03-13T23:16:49.039519Z","end":"2026-03-13T23:16:49.295488Z","steps":["trace[146126229] 'process raft request'  (duration: 255.822613ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:16:49.486584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.112341ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12737478172530004133 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:7339 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-13T23:16:49.486752Z","caller":"traceutil/trace.go:171","msg":"trace[413339047] linearizableReadLoop","detail":"{readStateIndex:7624; appliedIndex:7622; }","duration":"255.817362ms","start":"2026-03-13T23:16:49.230918Z","end":"2026-03-13T23:16:49.486735Z","steps":["trace[413339047] 'read index received'  (duration: 64.350199ms)","trace[413339047] 'applied index is now lower than readState.Index'  (duration: 191.465503ms)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:16:49.486851Z","caller":"traceutil/trace.go:171","msg":"trace[910708953] transaction","detail":"{read_only:false; response_revision:7348; number_of_response:1; }","duration":"446.11664ms","start":"2026-03-13T23:16:49.040722Z","end":"2026-03-13T23:16:49.486839Z","steps":["trace[910708953] 'process raft request'  (duration: 313.648332ms)","trace[910708953] 'compare'  (duration: 131.984718ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-13T23:16:49.486913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:16:49.040714Z","time spent":"446.166241ms","remote":"127.0.0.1:59582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:7339 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-03-13T23:16:49.486993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.056687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-95l2c\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-03-13T23:16:49.487067Z","caller":"traceutil/trace.go:171","msg":"trace[245990317] range","detail":"{range_begin:/registry/pods/openstack/keepalived-95l2c; range_end:; response_count:1; response_revision:7348; }","duration":"256.168659ms","start":"2026-03-13T23:16:49.230879Z","end":"2026-03-13T23:16:49.487048Z","steps":["trace[245990317] 'agreement among raft nodes before linearized reading'  (duration: 255.966514ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:17:00.955449Z","caller":"traceutil/trace.go:171","msg":"trace[900649525] linearizableReadLoop","detail":"{readStateIndex:7680; appliedIndex:7679; }","duration":"100.234383ms","start":"2026-03-13T23:17:00.85519Z","end":"2026-03-13T23:17:00.955424Z","steps":["trace[900649525] 'read index received'  (duration: 100.001318ms)","trace[900649525] 'applied index is now lower than readState.Index'  (duration: 231.905µs)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:17:00.955601Z","caller":"traceutil/trace.go:171","msg":"trace[1559797831] transaction","detail":"{read_only:false; response_revision:7402; number_of_response:1; }","duration":"115.133819ms","start":"2026-03-13T23:17:00.840402Z","end":"2026-03-13T23:17:00.955536Z","steps":["trace[1559797831] 'process raft request'  (duration: 114.861343ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:17:00.955708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.508048ms","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-03-13T23:17:00.95575Z","caller":"traceutil/trace.go:171","msg":"trace[1841422748] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7402; }","duration":"100.631821ms","start":"2026-03-13T23:17:00.855108Z","end":"2026-03-13T23:17:00.95574Z","steps":["trace[1841422748] 'agreement among raft nodes before linearized reading'  (duration: 100.458558ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:17:01.661334Z","caller":"traceutil/trace.go:171","msg":"trace[252316743] transaction","detail":"{read_only:false; response_revision:7406; number_of_response:1; }","duration":"114.489426ms","start":"2026-03-13T23:17:01.546798Z","end":"2026-03-13T23:17:01.661287Z","steps":["trace[252316743] 'process raft request'  (duration: 114.251831ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:17:01.904859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.318168ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12737478172530004321 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:7399 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-13T23:17:01.905015Z","caller":"traceutil/trace.go:171","msg":"trace[624664624] linearizableReadLoop","detail":"{readStateIndex:7685; appliedIndex:7684; }","duration":"237.990263ms","start":"2026-03-13T23:17:01.667Z","end":"2026-03-13T23:17:01.90499Z","steps":["trace[624664624] 'read index received'  (duration: 8.353758ms)","trace[624664624] 'applied index is now lower than readState.Index'  (duration: 229.634195ms)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:17:01.905055Z","caller":"traceutil/trace.go:171","msg":"trace[256232053] transaction","detail":"{read_only:false; response_revision:7407; number_of_response:1; }","duration":"239.451154ms","start":"2026-03-13T23:17:01.665556Z","end":"2026-03-13T23:17:01.905008Z","steps":["trace[256232053] 'compare'  (duration: 229.141515ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:17:01.905561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.534244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207049"}
{"level":"info","ts":"2026-03-13T23:17:01.905653Z","caller":"traceutil/trace.go:171","msg":"trace[511314340] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7407; }","duration":"238.669498ms","start":"2026-03-13T23:17:01.666966Z","end":"2026-03-13T23:17:01.905635Z","steps":["trace[511314340] 'agreement among raft nodes before linearized reading'  (duration: 238.085735ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:17:02.637201Z","caller":"traceutil/trace.go:171","msg":"trace[1954451213] transaction","detail":"{read_only:false; response_revision:7409; number_of_response:1; }","duration":"141.729494ms","start":"2026-03-13T23:17:02.495445Z","end":"2026-03-13T23:17:02.637174Z","steps":["trace[1954451213] 'process raft request'  (duration: 141.566061ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:17:02.786395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.29341ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12737478172530004328 > lease_revoke:<id:30c49ce96ff1508b>","response":"size:29"}
{"level":"info","ts":"2026-03-13T23:17:27.965204Z","caller":"traceutil/trace.go:171","msg":"trace[1061048090] transaction","detail":"{read_only:false; response_revision:7587; number_of_response:1; }","duration":"173.824406ms","start":"2026-03-13T23:17:27.791351Z","end":"2026-03-13T23:17:27.965175Z","steps":["trace[1061048090] 'process raft request'  (duration: 173.682023ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:17:33.940968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5592}
{"level":"info","ts":"2026-03-13T23:17:34.027675Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5592,"took":"84.370515ms","hash":867014970,"current-db-size-bytes":42766336,"current-db-size":"43 MB","current-db-size-in-use-bytes":22470656,"current-db-size-in-use":"22 MB"}
{"level":"info","ts":"2026-03-13T23:17:34.027754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":867014970,"revision":5592,"compact-revision":2592}
{"level":"warn","ts":"2026-03-13T23:17:40.976937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.620391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-13T23:17:40.97707Z","caller":"traceutil/trace.go:171","msg":"trace[232720528] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:7689; }","duration":"101.857135ms","start":"2026-03-13T23:17:40.875186Z","end":"2026-03-13T23:17:40.977043Z","steps":["trace[232720528] 'count revisions from in-memory index tree'  (duration: 101.483938ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:17:41.273136Z","caller":"traceutil/trace.go:171","msg":"trace[1976746235] transaction","detail":"{read_only:false; response_revision:7691; number_of_response:1; }","duration":"115.309743ms","start":"2026-03-13T23:17:41.157785Z","end":"2026-03-13T23:17:41.273095Z","steps":["trace[1976746235] 'process raft request'  (duration: 115.077848ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:17:42.092327Z","caller":"traceutil/trace.go:171","msg":"trace[732004025] linearizableReadLoop","detail":"{readStateIndex:7988; appliedIndex:7987; }","duration":"175.014622ms","start":"2026-03-13T23:17:41.917287Z","end":"2026-03-13T23:17:42.092302Z","steps":["trace[732004025] 'read index received'  (duration: 174.45064ms)","trace[732004025] 'applied index is now lower than readState.Index'  (duration: 560.182µs)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:17:42.092371Z","caller":"traceutil/trace.go:171","msg":"trace[1484794124] transaction","detail":"{read_only:false; response_revision:7696; number_of_response:1; }","duration":"185.324702ms","start":"2026-03-13T23:17:41.907023Z","end":"2026-03-13T23:17:42.092348Z","steps":["trace[1484794124] 'process raft request'  (duration: 184.714609ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:17:42.092624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.321679ms","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-13T23:17:42.092713Z","caller":"traceutil/trace.go:171","msg":"trace[1463134458] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7696; }","duration":"175.464921ms","start":"2026-03-13T23:17:41.917225Z","end":"2026-03-13T23:17:42.09269Z","steps":["trace[1463134458] 'agreement among raft nodes before linearized reading'  (duration: 175.238237ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:17:42.666055Z","caller":"traceutil/trace.go:171","msg":"trace[330000181] linearizableReadLoop","detail":"{readStateIndex:7994; appliedIndex:7993; }","duration":"154.206199ms","start":"2026-03-13T23:17:42.511825Z","end":"2026-03-13T23:17:42.666031Z","steps":["trace[330000181] 'read index received'  (duration: 154.043456ms)","trace[330000181] 'applied index is now lower than readState.Index'  (duration: 161.483µs)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:17:42.666123Z","caller":"traceutil/trace.go:171","msg":"trace[1624823327] transaction","detail":"{read_only:false; response_revision:7702; number_of_response:1; }","duration":"155.666579ms","start":"2026-03-13T23:17:42.510418Z","end":"2026-03-13T23:17:42.666084Z","steps":["trace[1624823327] 'process raft request'  (duration: 155.449285ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:17:42.666234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.390553ms","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-13T23:17:42.666276Z","caller":"traceutil/trace.go:171","msg":"trace[1426485863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7702; }","duration":"154.472685ms","start":"2026-03-13T23:17:42.511793Z","end":"2026-03-13T23:17:42.666266Z","steps":["trace[1426485863] 'agreement among raft nodes before linearized reading'  (duration: 154.332682ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-13T23:17:42.789449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.037269ms","expected-duration":"100ms","prefix":"","request":"header:<ID:12737478172530005355 > lease_revoke:<id:30c49ce96ff153c8>","response":"size:29"}
{"level":"info","ts":"2026-03-13T23:17:42.789784Z","caller":"traceutil/trace.go:171","msg":"trace[1731416131] transaction","detail":"{read_only:false; response_revision:7703; number_of_response:1; }","duration":"117.253204ms","start":"2026-03-13T23:17:42.672509Z","end":"2026-03-13T23:17:42.789762Z","steps":["trace[1731416131] 'process raft request'  (duration: 117.08542ms)"],"step_count":1}
{"level":"info","ts":"2026-03-13T23:18:49.892615Z","caller":"traceutil/trace.go:171","msg":"trace[998759020] transaction","detail":"{read_only:false; response_revision:8302; number_of_response:1; }","duration":"112.322129ms","start":"2026-03-13T23:18:49.780233Z","end":"2026-03-13T23:18:49.892555Z","steps":["trace[998759020] 'process raft request'  (duration: 79.093072ms)","trace[998759020] 'compare'  (duration: 32.982152ms)"],"step_count":2}
{"level":"info","ts":"2026-03-13T23:21:52.655011Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d2c3f297b56db0c4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-13T23:21:52.660523Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-03-13T23:21:52.660745Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-03-13T23:22:33.954677Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7640}
{"level":"info","ts":"2026-03-13T23:22:34.021339Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7640,"took":"62.024444ms","hash":1922767653,"current-db-size-bytes":42766336,"current-db-size":"43 MB","current-db-size-in-use-bytes":25481216,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-03-13T23:22:34.0214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1922767653,"revision":7640,"compact-revision":5592}
{"level":"info","ts":"2026-03-13T23:27:33.963511Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9843}
{"level":"info","ts":"2026-03-13T23:27:34.041446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9843,"took":"75.345768ms","hash":3799875142,"current-db-size-bytes":42766336,"current-db-size":"43 MB","current-db-size-in-use-bytes":16416768,"current-db-size-in-use":"16 MB"}
{"level":"info","ts":"2026-03-13T23:27:34.041524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3799875142,"revision":9843,"compact-revision":7640}