{"level":"warn","ts":"2026-04-02T16:48:07.096551Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-02T16:48:07.096662Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-04-02T16:48:07.096735Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-02T16:48:07.096749Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]}
{"level":"info","ts":"2026-04-02T16:48:07.096773Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-02T16:48:07.097433Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]}
{"level":"info","ts":"2026-04-02T16:48:07.097519Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-04-02T16:48:07.101857Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.103766ms"}
{"level":"info","ts":"2026-04-02T16:48:07.11118Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"}
{"level":"info","ts":"2026-04-02T16:48:07.1113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"}
{"level":"info","ts":"2026-04-02T16:48:07.111338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"}
{"level":"info","ts":"2026-04-02T16:48:07.111367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-02T16:48:07.111391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"}
{"level":"info","ts":"2026-04-02T16:48:07.111449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"}
{"level":"warn","ts":"2026-04-02T16:48:07.120471Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-02T16:48:07.123371Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-02T16:48:07.125364Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-04-02T16:48:07.127621Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-02T16:48:07.127744Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-02T16:48:07.127906Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-02T16:48:07.127974Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-02T16:48:07.127989Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-04-02T16:48:07.129004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"}
{"level":"info","ts":"2026-04-02T16:48:07.130116Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]}
{"level":"info","ts":"2026-04-02T16:48:07.133724Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-04-02T16:48:07.133899Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"}
{"level":"info","ts":"2026-04-02T16:48:07.133937Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"}
{"level":"info","ts":"2026-04-02T16:48:07.134165Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-02T16:48:07.134215Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-02T16:48:07.715554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"}
{"level":"info","ts":"2026-04-02T16:48:07.715695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-02T16:48:07.715718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"}
{"level":"info","ts":"2026-04-02T16:48:07.715737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"}
{"level":"info","ts":"2026-04-02T16:48:07.715842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"}
{"level":"info","ts":"2026-04-02T16:48:07.715859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"}
{"level":"info","ts":"2026-04-02T16:48:07.715872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"}
{"level":"info","ts":"2026-04-02T16:48:07.717272Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-02T16:48:07.717315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-02T16:48:07.717336Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-02T16:48:07.717411Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-02T16:48:07.717679Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-02T16:48:07.717714Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-02T16:48:07.7184Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-02T16:48:07.718479Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-02T16:48:07.718502Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-02T16:48:07.72031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-02T16:48:07.72415Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"}
{"level":"info","ts":"2026-04-02T16:48:36.956873Z","caller":"traceutil/trace.go:171","msg":"trace[2018289395] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"237.627208ms","start":"2026-04-02T16:48:36.719207Z","end":"2026-04-02T16:48:36.956834Z","steps":["trace[2018289395] 'process raft request'  (duration: 237.31699ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.958088Z","caller":"traceutil/trace.go:171","msg":"trace[391048919] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"228.645024ms","start":"2026-04-02T16:48:36.728353Z","end":"2026-04-02T16:48:36.956998Z","steps":["trace[391048919] 'process raft request'  (duration: 228.568212ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.958431Z","caller":"traceutil/trace.go:171","msg":"trace[1950529861] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"230.060314ms","start":"2026-04-02T16:48:36.728319Z","end":"2026-04-02T16:48:36.958379Z","steps":["trace[1950529861] 'process raft request'  (duration: 228.50539ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.956972Z","caller":"traceutil/trace.go:171","msg":"trace[1456066212] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"237.224107ms","start":"2026-04-02T16:48:36.719715Z","end":"2026-04-02T16:48:36.95694Z","steps":["trace[1456066212] 'process raft request'  (duration: 237.024892ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.958817Z","caller":"traceutil/trace.go:171","msg":"trace[120648147] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"225.627169ms","start":"2026-04-02T16:48:36.73302Z","end":"2026-04-02T16:48:36.958647Z","steps":["trace[120648147] 'process raft request'  (duration: 225.175756ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.958888Z","caller":"traceutil/trace.go:171","msg":"trace[10012750] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"227.143142ms","start":"2026-04-02T16:48:36.731421Z","end":"2026-04-02T16:48:36.958564Z","steps":["trace[10012750] 'process raft request'  (duration: 226.585316ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.959319Z","caller":"traceutil/trace.go:171","msg":"trace[1298030848] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"225.634969ms","start":"2026-04-02T16:48:36.733483Z","end":"2026-04-02T16:48:36.959118Z","steps":["trace[1298030848] 'process raft request'  (duration: 225.122775ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.961541Z","caller":"traceutil/trace.go:171","msg":"trace[2088055189] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"212.944499ms","start":"2026-04-02T16:48:36.748546Z","end":"2026-04-02T16:48:36.961491Z","steps":["trace[2088055189] 'process raft request'  (duration: 211.767146ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.96189Z","caller":"traceutil/trace.go:171","msg":"trace[1316151789] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"213.259489ms","start":"2026-04-02T16:48:36.748608Z","end":"2026-04-02T16:48:36.961867Z","steps":["trace[1316151789] 'process raft request'  (duration: 212.706094ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.972254Z","caller":"traceutil/trace.go:171","msg":"trace[1917947075] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"205.5099ms","start":"2026-04-02T16:48:36.766706Z","end":"2026-04-02T16:48:36.972216Z","steps":["trace[1917947075] 'process raft request'  (duration: 205.238793ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.972416Z","caller":"traceutil/trace.go:171","msg":"trace[226891227] transaction","detail":"{read_only:false; number_of_response:1; response_revision:519; }","duration":"203.135653ms","start":"2026-04-02T16:48:36.769227Z","end":"2026-04-02T16:48:36.972362Z","steps":["trace[226891227] 'process raft request'  (duration: 202.911306ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.972486Z","caller":"traceutil/trace.go:171","msg":"trace[2043356564] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"188.904059ms","start":"2026-04-02T16:48:36.783569Z","end":"2026-04-02T16:48:36.972473Z","steps":["trace[2043356564] 'process raft request'  (duration: 188.681102ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.972372Z","caller":"traceutil/trace.go:171","msg":"trace[729335159] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"204.289214ms","start":"2026-04-02T16:48:36.767996Z","end":"2026-04-02T16:48:36.972285Z","steps":["trace[729335159] 'process raft request'  (duration: 204.071838ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:36.972605Z","caller":"traceutil/trace.go:171","msg":"trace[1157574785] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:529; }","duration":"162.754448ms","start":"2026-04-02T16:48:36.809774Z","end":"2026-04-02T16:48:36.972528Z","steps":["trace[1157574785] 'read index received'  (duration: 146.93285ms)","trace[1157574785] 'applied index is now lower than readState.Index'  (duration: 15.820168ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T16:48:36.972977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.077358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-04-02T16:48:36.97312Z","caller":"traceutil/trace.go:171","msg":"trace[1547390914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"163.361436ms","start":"2026-04-02T16:48:36.809718Z","end":"2026-04-02T16:48:36.973079Z","steps":["trace[1547390914] 'agreement among raft nodes before linearized reading'  (duration: 162.972475ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:48:36.976505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.934148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-04-02T16:48:36.976588Z","caller":"traceutil/trace.go:171","msg":"trace[1922899050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"107.070531ms","start":"2026-04-02T16:48:36.869501Z","end":"2026-04-02T16:48:36.976571Z","steps":["trace[1922899050] 'agreement among raft nodes before linearized reading'  (duration: 106.896456ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:48:43.25011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.97182ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945805566 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" mod_revision:0 > success:<request_put:<key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" value_size:1527 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-04-02T16:48:43.250277Z","caller":"traceutil/trace.go:171","msg":"trace[647886693] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"280.252916ms","start":"2026-04-02T16:48:42.969994Z","end":"2026-04-02T16:48:43.250247Z","steps":["trace[647886693] 'process raft request'  (duration: 125.627017ms)","trace[647886693] 'compare'  (duration: 153.814966ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T16:48:43.25042Z","caller":"traceutil/trace.go:171","msg":"trace[752474373] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"230.020914ms","start":"2026-04-02T16:48:43.020369Z","end":"2026-04-02T16:48:43.25039Z","steps":["trace[752474373] 'read index received'  (duration: 75.252521ms)","trace[752474373] 'applied index is now lower than readState.Index'  (duration: 154.764423ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T16:48:43.250719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.340952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"warn","ts":"2026-04-02T16:48:43.250763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.324154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-04-02T16:48:43.250815Z","caller":"traceutil/trace.go:171","msg":"trace[152895648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"229.404726ms","start":"2026-04-02T16:48:43.021395Z","end":"2026-04-02T16:48:43.250799Z","steps":["trace[152895648] 'agreement among raft nodes before linearized reading'  (duration: 229.242482ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:43.250765Z","caller":"traceutil/trace.go:171","msg":"trace[179315343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"230.438425ms","start":"2026-04-02T16:48:43.020314Z","end":"2026-04-02T16:48:43.250753Z","steps":["trace[179315343] 'agreement among raft nodes before linearized reading'  (duration: 230.25093ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:43.452467Z","caller":"traceutil/trace.go:171","msg":"trace[1746115610] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"197.795971ms","start":"2026-04-02T16:48:43.254649Z","end":"2026-04-02T16:48:43.452445Z","steps":["trace[1746115610] 'process raft request'  (duration: 194.828777ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:48:43.459466Z","caller":"traceutil/trace.go:171","msg":"trace[1267344607] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"194.249401ms","start":"2026-04-02T16:48:43.265202Z","end":"2026-04-02T16:48:43.459452Z","steps":["trace[1267344607] 'read index received'  (duration: 184.226007ms)","trace[1267344607] 'applied index is now lower than readState.Index'  (duration: 10.022494ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T16:48:43.459531Z","caller":"traceutil/trace.go:171","msg":"trace[1355890086] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"204.338086ms","start":"2026-04-02T16:48:43.255188Z","end":"2026-04-02T16:48:43.459526Z","steps":["trace[1355890086] 'process raft request'  (duration: 204.204823ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:48:43.459712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.461047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-02T16:48:43.459815Z","caller":"traceutil/trace.go:171","msg":"trace[1518558982] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"194.633372ms","start":"2026-04-02T16:48:43.265159Z","end":"2026-04-02T16:48:43.459793Z","steps":["trace[1518558982] 'agreement among raft nodes before linearized reading'  (duration: 194.452887ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:48:43.881444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.437525ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945805574 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/cilium-4rzfm\" mod_revision:500 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-4rzfm\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-4rzfm\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-02T16:48:43.881555Z","caller":"traceutil/trace.go:171","msg":"trace[403401748] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"408.110056ms","start":"2026-04-02T16:48:43.473427Z","end":"2026-04-02T16:48:43.881537Z","steps":["trace[403401748] 'process raft request'  (duration: 284.362932ms)","trace[403401748] 'compare'  (duration: 123.119796ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T16:48:43.881799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:48:43.4734Z","time spent":"408.188228ms","remote":"127.0.0.1:50402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-4rzfm\" mod_revision:500 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-4rzfm\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-4rzfm\" > >"}
{"level":"info","ts":"2026-04-02T16:48:43.972107Z","caller":"traceutil/trace.go:171","msg":"trace[494939773] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"134.435007ms","start":"2026-04-02T16:48:43.837619Z","end":"2026-04-02T16:48:43.972054Z","steps":["trace[494939773] 'process raft request'  (duration: 133.812458ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:50:11.412843Z","caller":"traceutil/trace.go:171","msg":"trace[860384555] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"217.968408ms","start":"2026-04-02T16:50:11.194858Z","end":"2026-04-02T16:50:11.412826Z","steps":["trace[860384555] 'process raft request'  (duration: 217.844744ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:50:11.426199Z","caller":"traceutil/trace.go:171","msg":"trace[1429927971] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"226.38307ms","start":"2026-04-02T16:50:11.199782Z","end":"2026-04-02T16:50:11.426165Z","steps":["trace[1429927971] 'process raft request'  (duration: 226.114412ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:51:00.163384Z","caller":"traceutil/trace.go:171","msg":"trace[1307751457] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"148.234296ms","start":"2026-04-02T16:51:00.015132Z","end":"2026-04-02T16:51:00.163366Z","steps":["trace[1307751457] 'process raft request'  (duration: 148.133733ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:51:00.174545Z","caller":"traceutil/trace.go:171","msg":"trace[1232911304] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"129.319714ms","start":"2026-04-02T16:51:00.045207Z","end":"2026-04-02T16:51:00.174527Z","steps":["trace[1232911304] 'process raft request'  (duration: 129.176581ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:51:41.158657Z","caller":"traceutil/trace.go:171","msg":"trace[1785919648] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"121.96594ms","start":"2026-04-02T16:51:41.036657Z","end":"2026-04-02T16:51:41.158623Z","steps":["trace[1785919648] 'process raft request'  (duration: 121.773364ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:51:41.184067Z","caller":"traceutil/trace.go:171","msg":"trace[1014512005] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"131.898691ms","start":"2026-04-02T16:51:41.052132Z","end":"2026-04-02T16:51:41.184031Z","steps":["trace[1014512005] 'process raft request'  (duration: 131.700306ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:52:14.710537Z","caller":"traceutil/trace.go:171","msg":"trace[568723485] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"153.903039ms","start":"2026-04-02T16:52:14.55657Z","end":"2026-04-02T16:52:14.710473Z","steps":["trace[568723485] 'process raft request'  (duration: 55.618901ms)","trace[568723485] 'compare'  (duration: 98.103373ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T16:54:35.048319Z","caller":"traceutil/trace.go:171","msg":"trace[1314401551] linearizableReadLoop","detail":"{readStateIndex:3825; appliedIndex:3823; }","duration":"168.001325ms","start":"2026-04-02T16:54:34.880293Z","end":"2026-04-02T16:54:35.048294Z","steps":["trace[1314401551] 'read index received'  (duration: 92.254158ms)","trace[1314401551] 'applied index is now lower than readState.Index'  (duration: 75.745887ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T16:54:35.04855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.42441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-02T16:54:35.048553Z","caller":"traceutil/trace.go:171","msg":"trace[495968364] transaction","detail":"{read_only:false; response_revision:3686; number_of_response:1; }","duration":"168.279773ms","start":"2026-04-02T16:54:34.880196Z","end":"2026-04-02T16:54:35.048476Z","steps":["trace[495968364] 'process raft request'  (duration: 167.964745ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:54:35.048645Z","caller":"traceutil/trace.go:171","msg":"trace[1061170082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3686; }","duration":"104.566564ms","start":"2026-04-02T16:54:34.944058Z","end":"2026-04-02T16:54:35.048624Z","steps":["trace[1061170082] 'agreement among raft nodes before linearized reading'  (duration: 104.41107ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:54:35.048934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.615882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-04-02T16:54:35.048999Z","caller":"traceutil/trace.go:171","msg":"trace[433427222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3686; }","duration":"168.713865ms","start":"2026-04-02T16:54:34.880269Z","end":"2026-04-02T16:54:35.048983Z","steps":["trace[433427222] 'agreement among raft nodes before linearized reading'  (duration: 168.16301ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:55:51.799859Z","caller":"traceutil/trace.go:171","msg":"trace[814025446] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"113.478541ms","start":"2026-04-02T16:55:51.68634Z","end":"2026-04-02T16:55:51.799818Z","steps":["trace[814025446] 'process raft request'  (duration: 113.275424ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:55:56.564384Z","caller":"traceutil/trace.go:171","msg":"trace[1271828794] linearizableReadLoop","detail":"{readStateIndex:5020; appliedIndex:5019; }","duration":"152.476495ms","start":"2026-04-02T16:55:56.411877Z","end":"2026-04-02T16:55:56.564354Z","steps":["trace[1271828794] 'read index received'  (duration: 152.385792ms)","trace[1271828794] 'applied index is now lower than readState.Index'  (duration: 89.013µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T16:55:56.564513Z","caller":"traceutil/trace.go:171","msg":"trace[607114344] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"159.62171ms","start":"2026-04-02T16:55:56.404849Z","end":"2026-04-02T16:55:56.564471Z","steps":["trace[607114344] 'process raft request'  (duration: 159.313051ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:55:56.564668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.757792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"}
{"level":"info","ts":"2026-04-02T16:55:56.56472Z","caller":"traceutil/trace.go:171","msg":"trace[254275534] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4836; }","duration":"152.844964ms","start":"2026-04-02T16:55:56.41186Z","end":"2026-04-02T16:55:56.564704Z","steps":["trace[254275534] 'agreement among raft nodes before linearized reading'  (duration: 152.615668ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:55:56.752097Z","caller":"traceutil/trace.go:171","msg":"trace[808382499] linearizableReadLoop","detail":"{readStateIndex:5021; appliedIndex:5020; }","duration":"185.381524ms","start":"2026-04-02T16:55:56.566672Z","end":"2026-04-02T16:55:56.752054Z","steps":["trace[808382499] 'read index received'  (duration: 175.769501ms)","trace[808382499] 'applied index is now lower than readState.Index'  (duration: 9.609803ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T16:55:56.752185Z","caller":"traceutil/trace.go:171","msg":"trace[1477236840] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"239.10445ms","start":"2026-04-02T16:55:56.513051Z","end":"2026-04-02T16:55:56.752156Z","steps":["trace[1477236840] 'process raft request'  (duration: 229.513789ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:55:56.752535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.731646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"warn","ts":"2026-04-02T16:55:56.752577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.575273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6ldh2.18a29891abb4cb8d\" ","response":"range_response_count:1 size:962"}
{"level":"info","ts":"2026-04-02T16:55:56.752594Z","caller":"traceutil/trace.go:171","msg":"trace[1964797244] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4837; }","duration":"132.836549ms","start":"2026-04-02T16:55:56.619743Z","end":"2026-04-02T16:55:56.752579Z","steps":["trace[1964797244] 'agreement among raft nodes before linearized reading'  (duration: 132.579121ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:55:56.752639Z","caller":"traceutil/trace.go:171","msg":"trace[466533577] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-6ldh2.18a29891abb4cb8d; range_end:; response_count:1; response_revision:4837; }","duration":"165.650045ms","start":"2026-04-02T16:55:56.586976Z","end":"2026-04-02T16:55:56.752626Z","steps":["trace[466533577] 'agreement among raft nodes before linearized reading'  (duration: 165.387257ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:55:56.752762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.048462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"}
{"level":"warn","ts":"2026-04-02T16:55:56.752827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.4485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db11668f-263a-4dd2-8b1a-e8c2770b615d\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-04-02T16:55:56.752893Z","caller":"traceutil/trace.go:171","msg":"trace[1331573513] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4837; }","duration":"186.241397ms","start":"2026-04-02T16:55:56.566617Z","end":"2026-04-02T16:55:56.752859Z","steps":["trace[1331573513] 'agreement among raft nodes before linearized reading'  (duration: 185.814125ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:55:56.752913Z","caller":"traceutil/trace.go:171","msg":"trace[981217024] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db11668f-263a-4dd2-8b1a-e8c2770b615d; range_end:; response_count:1; response_revision:4837; }","duration":"172.584504ms","start":"2026-04-02T16:55:56.580306Z","end":"2026-04-02T16:55:56.752891Z","steps":["trace[981217024] 'agreement among raft nodes before linearized reading'  (duration: 171.973007ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:56:15.679723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.762744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-04-02T16:56:15.679811Z","caller":"traceutil/trace.go:171","msg":"trace[941362397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5234; }","duration":"107.938479ms","start":"2026-04-02T16:56:15.571855Z","end":"2026-04-02T16:56:15.679793Z","steps":["trace[941362397] 'range keys from in-memory index tree'  (duration: 107.508507ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T16:56:18.178731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.550877ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945816142 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:5238 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-02T16:56:18.178871Z","caller":"traceutil/trace.go:171","msg":"trace[1682308120] linearizableReadLoop","detail":"{readStateIndex:5445; appliedIndex:5444; }","duration":"234.146415ms","start":"2026-04-02T16:56:17.944702Z","end":"2026-04-02T16:56:18.178849Z","steps":["trace[1682308120] 'read index received'  (duration: 115.245038ms)","trace[1682308120] 'applied index is now lower than readState.Index'  (duration: 118.899497ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T16:56:18.178885Z","caller":"traceutil/trace.go:171","msg":"trace[196297867] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"240.050927ms","start":"2026-04-02T16:56:17.938798Z","end":"2026-04-02T16:56:18.178849Z","steps":["trace[196297867] 'process raft request'  (duration: 121.244463ms)","trace[196297867] 'compare'  (duration: 118.371803ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T16:56:18.178984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.270589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-02T16:56:18.17905Z","caller":"traceutil/trace.go:171","msg":"trace[2050620571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5253; }","duration":"234.388652ms","start":"2026-04-02T16:56:17.944647Z","end":"2026-04-02T16:56:18.179036Z","steps":["trace[2050620571] 'agreement among raft nodes before linearized reading'  (duration: 234.28081ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T16:58:07.744233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3036}
{"level":"info","ts":"2026-04-02T16:58:07.828706Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3036,"took":"82.886309ms","hash":1508806330,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":32350208,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-04-02T16:58:07.828776Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1508806330,"revision":3036,"compact-revision":-1}
{"level":"info","ts":"2026-04-02T17:03:03.937445Z","caller":"traceutil/trace.go:171","msg":"trace[1830540653] transaction","detail":"{read_only:false; response_revision:8289; number_of_response:1; }","duration":"123.401923ms","start":"2026-04-02T17:03:03.814009Z","end":"2026-04-02T17:03:03.937411Z","steps":["trace[1830540653] 'process raft request'  (duration: 123.145657ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:03:04.86308Z","caller":"traceutil/trace.go:171","msg":"trace[764417904] linearizableReadLoop","detail":"{readStateIndex:8585; appliedIndex:8584; }","duration":"255.719851ms","start":"2026-04-02T17:03:04.607338Z","end":"2026-04-02T17:03:04.863058Z","steps":["trace[764417904] 'read index received'  (duration: 255.323189ms)","trace[764417904] 'applied index is now lower than readState.Index'  (duration: 395.552µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:03:04.863141Z","caller":"traceutil/trace.go:171","msg":"trace[306525406] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"418.589321ms","start":"2026-04-02T17:03:04.444521Z","end":"2026-04-02T17:03:04.863111Z","steps":["trace[306525406] 'process raft request'  (duration: 418.245461ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:03:04.863399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.03468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fvp44\" ","response":"range_response_count:1 size:6531"}
{"level":"warn","ts":"2026-04-02T17:03:04.863475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:03:04.444489Z","time spent":"418.726125ms","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414454,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" mod_revision:7796 > success:<request_put:<key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" value_size:414386 >> failure:<request_range:<key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" > >"}
{"level":"info","ts":"2026-04-02T17:03:04.863518Z","caller":"traceutil/trace.go:171","msg":"trace[1808177103] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fvp44; range_end:; response_count:1; response_revision:8294; }","duration":"256.221164ms","start":"2026-04-02T17:03:04.607272Z","end":"2026-04-02T17:03:04.863493Z","steps":["trace[1808177103] 'agreement among raft nodes before linearized reading'  (duration: 255.910186ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:03:05.405364Z","caller":"traceutil/trace.go:171","msg":"trace[1208225250] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"114.494361ms","start":"2026-04-02T17:03:05.290829Z","end":"2026-04-02T17:03:05.405323Z","steps":["trace[1208225250] 'process raft request'  (duration: 114.162553ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:03:07.751321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6198}
{"level":"info","ts":"2026-04-02T17:03:07.847194Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6198,"took":"93.611702ms","hash":3302147753,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":23097344,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-04-02T17:03:07.847275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3302147753,"revision":6198,"compact-revision":3036}
{"level":"info","ts":"2026-04-02T17:03:32.179422Z","caller":"traceutil/trace.go:171","msg":"trace[926957640] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"119.711554ms","start":"2026-04-02T17:03:32.059687Z","end":"2026-04-02T17:03:32.179398Z","steps":["trace[926957640] 'process raft request'  (duration: 119.338693ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:03:44.831851Z","caller":"traceutil/trace.go:171","msg":"trace[1314101486] linearizableReadLoop","detail":"{readStateIndex:8904; appliedIndex:8903; }","duration":"274.460982ms","start":"2026-04-02T17:03:44.557354Z","end":"2026-04-02T17:03:44.831815Z","steps":["trace[1314101486] 'read index received'  (duration: 274.116662ms)","trace[1314101486] 'applied index is now lower than readState.Index'  (duration: 342.579µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:03:44.831903Z","caller":"traceutil/trace.go:171","msg":"trace[1656960799] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"275.106779ms","start":"2026-04-02T17:03:44.556738Z","end":"2026-04-02T17:03:44.831845Z","steps":["trace[1656960799] 'process raft request'  (duration: 274.623266ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:03:44.83222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.832001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:3066"}
{"level":"info","ts":"2026-04-02T17:03:44.832337Z","caller":"traceutil/trace.go:171","msg":"trace[167309393] range","detail":"{range_begin:/registry/jobs/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8599; }","duration":"274.982786ms","start":"2026-04-02T17:03:44.55733Z","end":"2026-04-02T17:03:44.832312Z","steps":["trace[167309393] 'agreement among raft nodes before linearized reading'  (duration: 274.615796ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:03:44.832312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.918319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:1097"}
{"level":"info","ts":"2026-04-02T17:03:44.832423Z","caller":"traceutil/trace.go:171","msg":"trace[113257484] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8599; }","duration":"273.072463ms","start":"2026-04-02T17:03:44.559329Z","end":"2026-04-02T17:03:44.832401Z","steps":["trace[113257484] 'agreement among raft nodes before linearized reading'  (duration: 272.738354ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:03:44.832438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.869291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-ptv6r\" ","response":"range_response_count:1 size:4636"}
{"level":"info","ts":"2026-04-02T17:03:44.832761Z","caller":"traceutil/trace.go:171","msg":"trace[701389713] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-ptv6r; range_end:; response_count:1; response_revision:8599; }","duration":"144.314873ms","start":"2026-04-02T17:03:44.68826Z","end":"2026-04-02T17:03:44.832575Z","steps":["trace[701389713] 'agreement among raft nodes before linearized reading'  (duration: 143.81823ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:03:44.832812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.445326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"}
{"level":"info","ts":"2026-04-02T17:03:44.833038Z","caller":"traceutil/trace.go:171","msg":"trace[1125137361] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8599; }","duration":"272.713254ms","start":"2026-04-02T17:03:44.560292Z","end":"2026-04-02T17:03:44.833005Z","steps":["trace[1125137361] 'agreement among raft nodes before linearized reading'  (duration: 271.862761ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:03:45.580434Z","caller":"traceutil/trace.go:171","msg":"trace[404168770] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"195.508ms","start":"2026-04-02T17:03:45.384877Z","end":"2026-04-02T17:03:45.580385Z","steps":["trace[404168770] 'process raft request'  (duration: 96.455291ms)","trace[404168770] 'compare'  (duration: 98.70612ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:03:46.448776Z","caller":"traceutil/trace.go:171","msg":"trace[1648335904] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"203.775175ms","start":"2026-04-02T17:03:46.24496Z","end":"2026-04-02T17:03:46.448735Z","steps":["trace[1648335904] 'process raft request'  (duration: 203.511168ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:03:46.503733Z","caller":"traceutil/trace.go:171","msg":"trace[590405570] linearizableReadLoop","detail":"{readStateIndex:8922; appliedIndex:8920; }","duration":"235.267294ms","start":"2026-04-02T17:03:46.268431Z","end":"2026-04-02T17:03:46.503698Z","steps":["trace[590405570] 'read index received'  (duration: 180.058739ms)","trace[590405570] 'applied index is now lower than readState.Index'  (duration: 55.206195ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:03:46.504069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.566612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-02T17:03:46.504223Z","caller":"traceutil/trace.go:171","msg":"trace[175419131] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8614; }","duration":"235.795278ms","start":"2026-04-02T17:03:46.26838Z","end":"2026-04-02T17:03:46.504175Z","steps":["trace[175419131] 'agreement among raft nodes before linearized reading'  (duration: 235.51325ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:03:46.504228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.982477ms","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-04-02T17:03:46.504284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.824156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-04-02T17:03:46.504317Z","caller":"traceutil/trace.go:171","msg":"trace[1690406057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8614; }","duration":"209.08699ms","start":"2026-04-02T17:03:46.29521Z","end":"2026-04-02T17:03:46.504297Z","steps":["trace[1690406057] 'agreement among raft nodes before linearized reading'  (duration: 208.832633ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:03:46.50434Z","caller":"traceutil/trace.go:171","msg":"trace[1723253413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8614; }","duration":"199.904909ms","start":"2026-04-02T17:03:46.304423Z","end":"2026-04-02T17:03:46.504328Z","steps":["trace[1723253413] 'agreement among raft nodes before linearized reading'  (duration: 199.671553ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:03:46.506016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.944615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"}
{"level":"info","ts":"2026-04-02T17:03:46.506085Z","caller":"traceutil/trace.go:171","msg":"trace[756983918] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8614; }","duration":"195.044858ms","start":"2026-04-02T17:03:46.311024Z","end":"2026-04-02T17:03:46.506069Z","steps":["trace[756983918] 'agreement among raft nodes before linearized reading'  (duration: 193.076224ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:05:04.41007Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000025c2.wal"}
{"level":"info","ts":"2026-04-02T17:05:42.948221Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-02T17:05:42.955906Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-04-02T17:05:42.956099Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-04-02T17:08:07.758377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8319}
{"level":"info","ts":"2026-04-02T17:08:07.83958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8319,"took":"77.8177ms","hash":238178365,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":26677248,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-04-02T17:08:07.839695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":238178365,"revision":8319,"compact-revision":6198}
{"level":"info","ts":"2026-04-02T17:08:29.760388Z","caller":"traceutil/trace.go:171","msg":"trace[1481944757] transaction","detail":"{read_only:false; response_revision:11158; number_of_response:1; }","duration":"109.877899ms","start":"2026-04-02T17:08:29.650468Z","end":"2026-04-02T17:08:29.760346Z","steps":["trace[1481944757] 'process raft request'  (duration: 109.701044ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:13:07.765907Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11015}
{"level":"info","ts":"2026-04-02T17:13:07.854679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11015,"took":"85.386227ms","hash":491138689,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":27553792,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-04-02T17:13:07.854792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":491138689,"revision":11015,"compact-revision":8319}
{"level":"info","ts":"2026-04-02T17:14:21.034327Z","caller":"traceutil/trace.go:171","msg":"trace[1809784279] transaction","detail":"{read_only:false; response_revision:13557; number_of_response:1; }","duration":"139.954987ms","start":"2026-04-02T17:14:20.894321Z","end":"2026-04-02T17:14:21.034276Z","steps":["trace[1809784279] 'process raft request'  (duration: 137.326576ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:14:57.141092Z","caller":"traceutil/trace.go:171","msg":"trace[1317577669] transaction","detail":"{read_only:false; response_revision:13758; number_of_response:1; }","duration":"116.877687ms","start":"2026-04-02T17:14:57.024188Z","end":"2026-04-02T17:14:57.141066Z","steps":["trace[1317577669] 'process raft request'  (duration: 116.711873ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:14:58.112783Z","caller":"traceutil/trace.go:171","msg":"trace[1914064240] transaction","detail":"{read_only:false; response_revision:13763; number_of_response:1; }","duration":"149.989966ms","start":"2026-04-02T17:14:57.962763Z","end":"2026-04-02T17:14:58.112753Z","steps":["trace[1914064240] 'process raft request'  (duration: 149.775631ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:15:20.566874Z","caller":"traceutil/trace.go:171","msg":"trace[810949681] linearizableReadLoop","detail":"{readStateIndex:14562; appliedIndex:14561; }","duration":"149.807352ms","start":"2026-04-02T17:15:20.417034Z","end":"2026-04-02T17:15:20.566842Z","steps":["trace[810949681] 'read index received'  (duration: 149.511664ms)","trace[810949681] 'applied index is now lower than readState.Index'  (duration: 294.058µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:15:20.567066Z","caller":"traceutil/trace.go:171","msg":"trace[1959334975] transaction","detail":"{read_only:false; response_revision:14073; number_of_response:1; }","duration":"240.172258ms","start":"2026-04-02T17:15:20.326878Z","end":"2026-04-02T17:15:20.567051Z","steps":["trace[1959334975] 'process raft request'  (duration: 239.669404ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:15:20.567293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.303606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-04-02T17:15:20.567402Z","caller":"traceutil/trace.go:171","msg":"trace[714432884] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14073; }","duration":"143.472721ms","start":"2026-04-02T17:15:20.423906Z","end":"2026-04-02T17:15:20.567379Z","steps":["trace[714432884] 'agreement among raft nodes before linearized reading'  (duration: 143.237415ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:15:20.567549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.466849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-04-02T17:15:20.567683Z","caller":"traceutil/trace.go:171","msg":"trace[170604364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14073; }","duration":"150.685685ms","start":"2026-04-02T17:15:20.416954Z","end":"2026-04-02T17:15:20.56764Z","steps":["trace[170604364] 'agreement among raft nodes before linearized reading'  (duration: 150.284945ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:15:20.786779Z","caller":"traceutil/trace.go:171","msg":"trace[1172624511] transaction","detail":"{read_only:false; response_revision:14074; number_of_response:1; }","duration":"213.687126ms","start":"2026-04-02T17:15:20.573035Z","end":"2026-04-02T17:15:20.786722Z","steps":["trace[1172624511] 'process raft request'  (duration: 206.475013ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:15:20.789243Z","caller":"traceutil/trace.go:171","msg":"trace[1670873366] linearizableReadLoop","detail":"{readStateIndex:14564; appliedIndex:14562; }","duration":"143.340807ms","start":"2026-04-02T17:15:20.645862Z","end":"2026-04-02T17:15:20.789203Z","steps":["trace[1670873366] 'read index received'  (duration: 133.667668ms)","trace[1670873366] 'applied index is now lower than readState.Index'  (duration: 9.671489ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:15:20.789525Z","caller":"traceutil/trace.go:171","msg":"trace[1805437714] transaction","detail":"{read_only:false; response_revision:14075; number_of_response:1; }","duration":"215.967487ms","start":"2026-04-02T17:15:20.573515Z","end":"2026-04-02T17:15:20.789482Z","steps":["trace[1805437714] 'process raft request'  (duration: 215.527035ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:15:20.789747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.859282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-04-02T17:15:20.789864Z","caller":"traceutil/trace.go:171","msg":"trace[1343000928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14075; }","duration":"144.050277ms","start":"2026-04-02T17:15:20.645785Z","end":"2026-04-02T17:15:20.789835Z","steps":["trace[1343000928] 'agreement among raft nodes before linearized reading'  (duration: 143.652206ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:15:20.789857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.6378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-04-02T17:15:20.78999Z","caller":"traceutil/trace.go:171","msg":"trace[1354274947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14075; }","duration":"107.860146ms","start":"2026-04-02T17:15:20.682102Z","end":"2026-04-02T17:15:20.789962Z","steps":["trace[1354274947] 'agreement among raft nodes before linearized reading'  (duration: 107.460784ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:16:45.476641Z","caller":"traceutil/trace.go:171","msg":"trace[489234006] linearizableReadLoop","detail":"{readStateIndex:15387; appliedIndex:15386; }","duration":"226.979133ms","start":"2026-04-02T17:16:45.249621Z","end":"2026-04-02T17:16:45.4766Z","steps":["trace[489234006] 'read index received'  (duration: 226.684235ms)","trace[489234006] 'applied index is now lower than readState.Index'  (duration: 293.588µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:16:45.476696Z","caller":"traceutil/trace.go:171","msg":"trace[1263804330] transaction","detail":"{read_only:false; response_revision:14875; number_of_response:1; }","duration":"236.341004ms","start":"2026-04-02T17:16:45.240332Z","end":"2026-04-02T17:16:45.476673Z","steps":["trace[1263804330] 'process raft request'  (duration: 236.000855ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.476823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.173988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-02T17:16:45.476891Z","caller":"traceutil/trace.go:171","msg":"trace[1175062044] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14875; }","duration":"227.292871ms","start":"2026-04-02T17:16:45.249583Z","end":"2026-04-02T17:16:45.476876Z","steps":["trace[1175062044] 'agreement among raft nodes before linearized reading'  (duration: 227.151528ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.477019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.38624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-04-02T17:16:45.477085Z","caller":"traceutil/trace.go:171","msg":"trace[2129812349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14875; }","duration":"225.464541ms","start":"2026-04-02T17:16:45.251602Z","end":"2026-04-02T17:16:45.477066Z","steps":["trace[2129812349] 'agreement among raft nodes before linearized reading'  (duration: 225.262046ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.477162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.6791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"warn","ts":"2026-04-02T17:16:45.477243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.339379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"}
{"level":"info","ts":"2026-04-02T17:16:45.477275Z","caller":"traceutil/trace.go:171","msg":"trace[169520317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14875; }","duration":"218.824464ms","start":"2026-04-02T17:16:45.258427Z","end":"2026-04-02T17:16:45.477251Z","steps":["trace[169520317] 'agreement among raft nodes before linearized reading'  (duration: 218.459295ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.477329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.054168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zml9f\" ","response":"range_response_count:1 size:9799"}
{"level":"info","ts":"2026-04-02T17:16:45.477352Z","caller":"traceutil/trace.go:171","msg":"trace[1744563583] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14875; }","duration":"225.489442ms","start":"2026-04-02T17:16:45.251839Z","end":"2026-04-02T17:16:45.477329Z","steps":["trace[1744563583] 'agreement among raft nodes before linearized reading'  (duration: 225.102262ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:16:45.477386Z","caller":"traceutil/trace.go:171","msg":"trace[2133951740] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zml9f; range_end:; response_count:1; response_revision:14875; }","duration":"207.164351ms","start":"2026-04-02T17:16:45.270207Z","end":"2026-04-02T17:16:45.477371Z","steps":["trace[2133951740] 'agreement among raft nodes before linearized reading'  (duration: 206.967246ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.477372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.628481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-04-02T17:16:45.477389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.471912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"}
{"level":"info","ts":"2026-04-02T17:16:45.477674Z","caller":"traceutil/trace.go:171","msg":"trace[186856774] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14875; }","duration":"225.875763ms","start":"2026-04-02T17:16:45.251775Z","end":"2026-04-02T17:16:45.47765Z","steps":["trace[186856774] 'agreement among raft nodes before linearized reading'  (duration: 225.207005ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:16:45.477458Z","caller":"traceutil/trace.go:171","msg":"trace[1200858865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14875; }","duration":"173.730693ms","start":"2026-04-02T17:16:45.303702Z","end":"2026-04-02T17:16:45.477433Z","steps":["trace[1200858865] 'agreement among raft nodes before linearized reading'  (duration: 173.448986ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.720347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.303383ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945843475 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:14867 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-02T17:16:45.720666Z","caller":"traceutil/trace.go:171","msg":"trace[120778255] transaction","detail":"{read_only:false; response_revision:14878; number_of_response:1; }","duration":"236.433117ms","start":"2026-04-02T17:16:45.484209Z","end":"2026-04-02T17:16:45.720642Z","steps":["trace[120778255] 'process raft request'  (duration: 236.379585ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:16:45.720666Z","caller":"traceutil/trace.go:171","msg":"trace[49899599] linearizableReadLoop","detail":"{readStateIndex:15388; appliedIndex:15387; }","duration":"236.740986ms","start":"2026-04-02T17:16:45.483899Z","end":"2026-04-02T17:16:45.72064Z","steps":["trace[49899599] 'read index received'  (duration: 135.868768ms)","trace[49899599] 'applied index is now lower than readState.Index'  (duration: 100.869707ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:16:45.720834Z","caller":"traceutil/trace.go:171","msg":"trace[1808339800] transaction","detail":"{read_only:false; response_revision:14877; number_of_response:1; }","duration":"236.640412ms","start":"2026-04-02T17:16:45.484132Z","end":"2026-04-02T17:16:45.720772Z","steps":["trace[1808339800] 'process raft request'  (duration: 236.344314ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:16:45.720916Z","caller":"traceutil/trace.go:171","msg":"trace[10306975] transaction","detail":"{read_only:false; response_revision:14876; number_of_response:1; }","duration":"237.771263ms","start":"2026-04-02T17:16:45.482872Z","end":"2026-04-02T17:16:45.720643Z","steps":["trace[10306975] 'process raft request'  (duration: 137.030078ms)","trace[10306975] 'compare'  (duration: 99.872782ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:16:45.72099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.167179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"}
{"level":"info","ts":"2026-04-02T17:16:45.721031Z","caller":"traceutil/trace.go:171","msg":"trace[2144027532] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14878; }","duration":"217.235091ms","start":"2026-04-02T17:16:45.503787Z","end":"2026-04-02T17:16:45.721022Z","steps":["trace[2144027532] 'agreement among raft nodes before linearized reading'  (duration: 217.077866ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.721283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.440042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-02T17:16:45.721332Z","caller":"traceutil/trace.go:171","msg":"trace[1002211807] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:14878; }","duration":"158.516864ms","start":"2026-04-02T17:16:45.562802Z","end":"2026-04-02T17:16:45.721318Z","steps":["trace[1002211807] 'agreement among raft nodes before linearized reading'  (duration: 158.438942ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.721496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.700151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-04-02T17:16:45.721535Z","caller":"traceutil/trace.go:171","msg":"trace[418296857] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14878; }","duration":"107.857105ms","start":"2026-04-02T17:16:45.613657Z","end":"2026-04-02T17:16:45.721514Z","steps":["trace[418296857] 'agreement among raft nodes before linearized reading'  (duration: 107.811043ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:16:45.721666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.745692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737455"}
{"level":"info","ts":"2026-04-02T17:16:45.721718Z","caller":"traceutil/trace.go:171","msg":"trace[1942983094] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:14878; }","duration":"237.834975ms","start":"2026-04-02T17:16:45.483871Z","end":"2026-04-02T17:16:45.721706Z","steps":["trace[1942983094] 'agreement among raft nodes before linearized reading'  (duration: 236.89717ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:18:07.772639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13050}
{"level":"info","ts":"2026-04-02T17:18:07.856749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13050,"took":"80.708614ms","hash":3787605720,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":30867456,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-04-02T17:18:07.856809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3787605720,"revision":13050,"compact-revision":11015}
{"level":"info","ts":"2026-04-02T17:22:05.858342Z","caller":"traceutil/trace.go:171","msg":"trace[1011946907] transaction","detail":"{read_only:false; response_revision:17319; number_of_response:1; }","duration":"132.424283ms","start":"2026-04-02T17:22:05.72588Z","end":"2026-04-02T17:22:05.858305Z","steps":["trace[1011946907] 'process raft request'  (duration: 132.032592ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:22:11.412474Z","caller":"traceutil/trace.go:171","msg":"trace[398723743] transaction","detail":"{read_only:false; response_revision:17349; number_of_response:1; }","duration":"160.961444ms","start":"2026-04-02T17:22:11.25148Z","end":"2026-04-02T17:22:11.412442Z","steps":["trace[398723743] 'process raft request'  (duration: 160.752259ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:22:11.428353Z","caller":"traceutil/trace.go:171","msg":"trace[776183375] linearizableReadLoop","detail":"{readStateIndex:17944; appliedIndex:17944; }","duration":"157.596875ms","start":"2026-04-02T17:22:11.270717Z","end":"2026-04-02T17:22:11.428314Z","steps":["trace[776183375] 'read index received'  (duration: 157.585885ms)","trace[776183375] 'applied index is now lower than readState.Index'  (duration: 8.72µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:22:11.428684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.948445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-8f4xn\" ","response":"range_response_count:1 size:22133"}
{"level":"info","ts":"2026-04-02T17:22:11.428816Z","caller":"traceutil/trace.go:171","msg":"trace[1845689692] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-8f4xn; range_end:; response_count:1; response_revision:17349; }","duration":"158.13382ms","start":"2026-04-02T17:22:11.270661Z","end":"2026-04-02T17:22:11.428795Z","steps":["trace[1845689692] 'agreement among raft nodes before linearized reading'  (duration: 157.75061ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:22:11.85449Z","caller":"traceutil/trace.go:171","msg":"trace[732536520] linearizableReadLoop","detail":"{readStateIndex:17945; appliedIndex:17944; }","duration":"399.463528ms","start":"2026-04-02T17:22:11.454993Z","end":"2026-04-02T17:22:11.854456Z","steps":["trace[732536520] 'read index received'  (duration: 399.346205ms)","trace[732536520] 'applied index is now lower than readState.Index'  (duration: 115.253µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:22:11.854548Z","caller":"traceutil/trace.go:171","msg":"trace[1325902330] transaction","detail":"{read_only:false; response_revision:17350; number_of_response:1; }","duration":"401.743759ms","start":"2026-04-02T17:22:11.452746Z","end":"2026-04-02T17:22:11.854489Z","steps":["trace[1325902330] 'process raft request'  (duration: 401.516013ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:22:11.85489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:22:11.452733Z","time spent":"401.905193ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:17335 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-04-02T17:22:11.854983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.93165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-02T17:22:11.855133Z","caller":"traceutil/trace.go:171","msg":"trace[1407256518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17350; }","duration":"400.124895ms","start":"2026-04-02T17:22:11.45496Z","end":"2026-04-02T17:22:11.855085Z","steps":["trace[1407256518] 'agreement among raft nodes before linearized reading'  (duration: 399.649963ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:22:11.855219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:22:11.454943Z","time spent":"400.236778ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-04-02T17:22:11.882133Z","caller":"traceutil/trace.go:171","msg":"trace[1621799714] transaction","detail":"{read_only:false; response_revision:17351; number_of_response:1; }","duration":"226.269507ms","start":"2026-04-02T17:22:11.655837Z","end":"2026-04-02T17:22:11.882107Z","steps":["trace[1621799714] 'process raft request'  (duration: 225.99758ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:22:11.882348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.683804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"warn","ts":"2026-04-02T17:22:11.882448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.677051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-zml9f\" ","response":"range_response_count:1 size:9800"}
{"level":"info","ts":"2026-04-02T17:22:11.882516Z","caller":"traceutil/trace.go:171","msg":"trace[354583597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17351; }","duration":"414.878028ms","start":"2026-04-02T17:22:11.467595Z","end":"2026-04-02T17:22:11.882473Z","steps":["trace[354583597] 'agreement among raft nodes before linearized reading'  (duration: 414.472938ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:22:11.882532Z","caller":"traceutil/trace.go:171","msg":"trace[1661514078] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-zml9f; range_end:; response_count:1; response_revision:17351; }","duration":"225.779883ms","start":"2026-04-02T17:22:11.656733Z","end":"2026-04-02T17:22:11.882513Z","steps":["trace[1661514078] 'agreement among raft nodes before linearized reading'  (duration: 225.477765ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:22:11.882603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:22:11.467588Z","time spent":"414.997051ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-04-02T17:22:11.882385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.303925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"}
{"level":"info","ts":"2026-04-02T17:22:11.882838Z","caller":"traceutil/trace.go:171","msg":"trace[765433991] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17351; }","duration":"212.819978ms","start":"2026-04-02T17:22:11.670006Z","end":"2026-04-02T17:22:11.882826Z","steps":["trace[765433991] 'agreement among raft nodes before linearized reading'  (duration: 212.122669ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:23:07.780378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15428}
{"level":"info","ts":"2026-04-02T17:23:07.885151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15428,"took":"98.957314ms","hash":3784691140,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":33996800,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-04-02T17:23:07.885243Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3784691140,"revision":15428,"compact-revision":13050}
{"level":"info","ts":"2026-04-02T17:26:05.976537Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-02T17:26:05.982461Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-04-02T17:26:05.982579Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-04-02T17:28:05.938916Z","caller":"traceutil/trace.go:171","msg":"trace[2124194585] linearizableReadLoop","detail":"{readStateIndex:20747; appliedIndex:20746; }","duration":"130.338331ms","start":"2026-04-02T17:28:05.808548Z","end":"2026-04-02T17:28:05.938886Z","steps":["trace[2124194585] 'read index received'  (duration: 130.050793ms)","trace[2124194585] 'applied index is now lower than readState.Index'  (duration: 285.708µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:28:05.939078Z","caller":"traceutil/trace.go:171","msg":"trace[1821063215] transaction","detail":"{read_only:false; response_revision:20063; number_of_response:1; }","duration":"137.072172ms","start":"2026-04-02T17:28:05.801993Z","end":"2026-04-02T17:28:05.939066Z","steps":["trace[1821063215] 'process raft request'  (duration: 136.698093ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:28:05.939413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.841255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-04-02T17:28:05.939452Z","caller":"traceutil/trace.go:171","msg":"trace[1360890338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20063; }","duration":"130.940748ms","start":"2026-04-02T17:28:05.808495Z","end":"2026-04-02T17:28:05.939436Z","steps":["trace[1360890338] 'agreement among raft nodes before linearized reading'  (duration: 130.762043ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:28:05.939545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.227689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-04-02T17:28:05.939536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.877881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-04-02T17:28:05.939624Z","caller":"traceutil/trace.go:171","msg":"trace[2045742901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20063; }","duration":"117.328602ms","start":"2026-04-02T17:28:05.822275Z","end":"2026-04-02T17:28:05.939604Z","steps":["trace[2045742901] 'agreement among raft nodes before linearized reading'  (duration: 117.124506ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:28:05.939681Z","caller":"traceutil/trace.go:171","msg":"trace[599430509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20063; }","duration":"120.067965ms","start":"2026-04-02T17:28:05.819561Z","end":"2026-04-02T17:28:05.939629Z","steps":["trace[599430509] 'agreement among raft nodes before linearized reading'  (duration: 119.645974ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:28:06.093803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.741972ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945861066 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:20054 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:28:06.093933Z","caller":"traceutil/trace.go:171","msg":"trace[1311953445] transaction","detail":"{read_only:false; response_revision:20064; number_of_response:1; }","duration":"149.29072ms","start":"2026-04-02T17:28:05.944622Z","end":"2026-04-02T17:28:06.093913Z","steps":["trace[1311953445] 'process raft request'  (duration: 34.330692ms)","trace[1311953445] 'compare'  (duration: 114.508916ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:28:06.150574Z","caller":"traceutil/trace.go:171","msg":"trace[265886908] linearizableReadLoop","detail":"{readStateIndex:20750; appliedIndex:20747; }","duration":"204.182565ms","start":"2026-04-02T17:28:05.946356Z","end":"2026-04-02T17:28:06.150539Z","steps":["trace[265886908] 'read index received'  (duration: 32.680857ms)","trace[265886908] 'applied index is now lower than readState.Index'  (duration: 171.500528ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:28:06.150633Z","caller":"traceutil/trace.go:171","msg":"trace[770811676] transaction","detail":"{read_only:false; response_revision:20065; number_of_response:1; }","duration":"205.153132ms","start":"2026-04-02T17:28:05.945419Z","end":"2026-04-02T17:28:06.150572Z","steps":["trace[770811676] 'process raft request'  (duration: 204.860984ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:28:06.150644Z","caller":"traceutil/trace.go:171","msg":"trace[2122522952] transaction","detail":"{read_only:false; response_revision:20066; number_of_response:1; }","duration":"204.530835ms","start":"2026-04-02T17:28:05.946076Z","end":"2026-04-02T17:28:06.150607Z","steps":["trace[2122522952] 'process raft request'  (duration: 204.409992ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:28:06.150937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.547695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:28:06.150994Z","caller":"traceutil/trace.go:171","msg":"trace[1111405691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20066; }","duration":"204.651558ms","start":"2026-04-02T17:28:05.946328Z","end":"2026-04-02T17:28:06.15098Z","steps":["trace[1111405691] 'agreement among raft nodes before linearized reading'  (duration: 204.374641ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:28:06.300479Z","caller":"traceutil/trace.go:171","msg":"trace[14515978] linearizableReadLoop","detail":"{readStateIndex:20751; appliedIndex:20750; }","duration":"149.779393ms","start":"2026-04-02T17:28:06.150665Z","end":"2026-04-02T17:28:06.300444Z","steps":["trace[14515978] 'read index received'  (duration: 90.023748ms)","trace[14515978] 'applied index is now lower than readState.Index'  (duration: 59.753925ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:28:06.300761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.345318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"info","ts":"2026-04-02T17:28:06.300809Z","caller":"traceutil/trace.go:171","msg":"trace[1703237123] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20066; }","duration":"313.407479ms","start":"2026-04-02T17:28:05.987386Z","end":"2026-04-02T17:28:06.300794Z","steps":["trace[1703237123] 'agreement among raft nodes before linearized reading'  (duration: 313.176333ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:28:06.300846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:28:05.987305Z","time spent":"313.532083ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-04-02T17:28:06.717329Z","caller":"traceutil/trace.go:171","msg":"trace[1322945217] transaction","detail":"{read_only:false; response_revision:20068; number_of_response:1; }","duration":"121.168235ms","start":"2026-04-02T17:28:06.5961Z","end":"2026-04-02T17:28:06.717268Z","steps":["trace[1322945217] 'process raft request'  (duration: 120.943509ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:28:07.786748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18001}
{"level":"info","ts":"2026-04-02T17:28:07.90535Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18001,"took":"114.517117ms","hash":1500469642,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":29491200,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-04-02T17:28:07.90543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1500469642,"revision":18001,"compact-revision":15428}
{"level":"warn","ts":"2026-04-02T17:31:40.051383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.773321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:31:40.051509Z","caller":"traceutil/trace.go:171","msg":"trace[1957748546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22363; }","duration":"105.959777ms","start":"2026-04-02T17:31:39.945525Z","end":"2026-04-02T17:31:40.051485Z","steps":["trace[1957748546] 'range keys from in-memory index tree'  (duration: 105.603567ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:31:40.342319Z","caller":"traceutil/trace.go:171","msg":"trace[562316006] transaction","detail":"{read_only:false; response_revision:22365; number_of_response:1; }","duration":"114.564848ms","start":"2026-04-02T17:31:40.227727Z","end":"2026-04-02T17:31:40.342292Z","steps":["trace[562316006] 'process raft request'  (duration: 112.530853ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:33:07.793001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20075}
{"level":"info","ts":"2026-04-02T17:33:07.90326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20075,"took":"105.466683ms","hash":1346213698,"current-db-size-bytes":59047936,"current-db-size":"59 MB","current-db-size-in-use-bytes":41136128,"current-db-size-in-use":"41 MB"}
{"level":"info","ts":"2026-04-02T17:33:07.903413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1346213698,"revision":20075,"compact-revision":18001}
{"level":"info","ts":"2026-04-02T17:35:06.977119Z","caller":"traceutil/trace.go:171","msg":"trace[290456858] transaction","detail":"{read_only:false; response_revision:24460; number_of_response:1; }","duration":"118.949736ms","start":"2026-04-02T17:35:06.858148Z","end":"2026-04-02T17:35:06.977097Z","steps":["trace[290456858] 'process raft request'  (duration: 118.306458ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:07.380704Z","caller":"traceutil/trace.go:171","msg":"trace[617825048] transaction","detail":"{read_only:false; response_revision:24462; number_of_response:1; }","duration":"219.058285ms","start":"2026-04-02T17:35:07.161605Z","end":"2026-04-02T17:35:07.380663Z","steps":["trace[617825048] 'process raft request'  (duration: 125.913952ms)","trace[617825048] 'compare'  (duration: 92.981489ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:35:07.414973Z","caller":"traceutil/trace.go:171","msg":"trace[323807116] linearizableReadLoop","detail":"{readStateIndex:25272; appliedIndex:25271; }","duration":"126.520308ms","start":"2026-04-02T17:35:07.288436Z","end":"2026-04-02T17:35:07.414956Z","steps":["trace[323807116] 'read index received'  (duration: 126.416546ms)","trace[323807116] 'applied index is now lower than readState.Index'  (duration: 102.552µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:35:07.415146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.683013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-02T17:35:07.415231Z","caller":"traceutil/trace.go:171","msg":"trace[1933216959] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24464; }","duration":"126.841537ms","start":"2026-04-02T17:35:07.288373Z","end":"2026-04-02T17:35:07.415214Z","steps":["trace[1933216959] 'agreement among raft nodes before linearized reading'  (duration: 126.669263ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:07.414485Z","caller":"traceutil/trace.go:171","msg":"trace[339684157] transaction","detail":"{read_only:false; response_revision:24463; number_of_response:1; }","duration":"242.566127ms","start":"2026-04-02T17:35:07.171891Z","end":"2026-04-02T17:35:07.414457Z","steps":["trace[339684157] 'process raft request'  (duration: 242.333891ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:07.415751Z","caller":"traceutil/trace.go:171","msg":"trace[527914230] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"239.347139ms","start":"2026-04-02T17:35:07.176364Z","end":"2026-04-02T17:35:07.415711Z","steps":["trace[527914230] 'process raft request'  (duration: 238.507857ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:09.427381Z","caller":"traceutil/trace.go:171","msg":"trace[1056201955] linearizableReadLoop","detail":"{readStateIndex:25287; appliedIndex:25286; }","duration":"152.011074ms","start":"2026-04-02T17:35:09.275347Z","end":"2026-04-02T17:35:09.427358Z","steps":["trace[1056201955] 'read index received'  (duration: 151.825739ms)","trace[1056201955] 'applied index is now lower than readState.Index'  (duration: 183.855µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:35:09.427487Z","caller":"traceutil/trace.go:171","msg":"trace[1501469766] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"252.707648ms","start":"2026-04-02T17:35:09.174748Z","end":"2026-04-02T17:35:09.427456Z","steps":["trace[1501469766] 'process raft request'  (duration: 252.430631ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:09.427703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.27296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:35:09.428719Z","caller":"traceutil/trace.go:171","msg":"trace[789166509] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24479; }","duration":"153.389231ms","start":"2026-04-02T17:35:09.275273Z","end":"2026-04-02T17:35:09.428662Z","steps":["trace[789166509] 'agreement among raft nodes before linearized reading'  (duration: 152.226059ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:40.978544Z","caller":"traceutil/trace.go:171","msg":"trace[89106171] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"403.987985ms","start":"2026-04-02T17:35:40.574523Z","end":"2026-04-02T17:35:40.978511Z","steps":["trace[89106171] 'process raft request'  (duration: 403.77502ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:40.978875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:40.574487Z","time spent":"404.201201ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:24708 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-04-02T17:35:40.997143Z","caller":"traceutil/trace.go:171","msg":"trace[2018512060] linearizableReadLoop","detail":"{readStateIndex:25536; appliedIndex:25534; }","duration":"405.221539ms","start":"2026-04-02T17:35:40.59189Z","end":"2026-04-02T17:35:40.997111Z","steps":["trace[2018512060] 'read index received'  (duration: 386.514327ms)","trace[2018512060] 'applied index is now lower than readState.Index'  (duration: 18.704952ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:35:40.997283Z","caller":"traceutil/trace.go:171","msg":"trace[949418447] transaction","detail":"{read_only:false; response_revision:24722; number_of_response:1; }","duration":"412.151455ms","start":"2026-04-02T17:35:40.585073Z","end":"2026-04-02T17:35:40.997225Z","steps":["trace[949418447] 'process raft request'  (duration: 411.840696ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:40.997505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:40.585031Z","time spent":"412.35178ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24709 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-02T17:35:40.997526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.929071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:35:40.997544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.63623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"info","ts":"2026-04-02T17:35:40.997634Z","caller":"traceutil/trace.go:171","msg":"trace[568772708] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24722; }","duration":"329.092725ms","start":"2026-04-02T17:35:40.668495Z","end":"2026-04-02T17:35:40.997588Z","steps":["trace[568772708] 'agreement among raft nodes before linearized reading'  (duration: 328.883649ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:40.997626Z","caller":"traceutil/trace.go:171","msg":"trace[1497591508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24722; }","duration":"405.782064ms","start":"2026-04-02T17:35:40.591829Z","end":"2026-04-02T17:35:40.997612Z","steps":["trace[1497591508] 'agreement among raft nodes before linearized reading'  (duration: 405.462185ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:40.997722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:40.668472Z","time spent":"329.235929ms","remote":"127.0.0.1:34064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "}
{"level":"warn","ts":"2026-04-02T17:35:40.997729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:40.591806Z","time spent":"405.907807ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-04-02T17:35:40.997764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.590003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-02T17:35:40.997873Z","caller":"traceutil/trace.go:171","msg":"trace[915732928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24722; }","duration":"283.771479ms","start":"2026-04-02T17:35:40.714077Z","end":"2026-04-02T17:35:40.997849Z","steps":["trace[915732928] 'agreement among raft nodes before linearized reading'  (duration: 283.468841ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:40.997927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.086424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-04-02T17:35:40.998094Z","caller":"traceutil/trace.go:171","msg":"trace[287359077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24722; }","duration":"272.28875ms","start":"2026-04-02T17:35:40.725779Z","end":"2026-04-02T17:35:40.998068Z","steps":["trace[287359077] 'agreement among raft nodes before linearized reading'  (duration: 271.779486ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:41.290708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.5539ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945873479 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:35db9d4f1852d646>","response":"size:40"}
{"level":"info","ts":"2026-04-02T17:35:41.290989Z","caller":"traceutil/trace.go:171","msg":"trace[376423654] linearizableReadLoop","detail":"{readStateIndex:25540; appliedIndex:25536; }","duration":"160.180499ms","start":"2026-04-02T17:35:41.130793Z","end":"2026-04-02T17:35:41.290974Z","steps":["trace[376423654] 'read index received'  (duration: 89.911723ms)","trace[376423654] 'applied index is now lower than readState.Index'  (duration: 70.267406ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:35:41.291266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.464229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:35:41.291233Z","caller":"traceutil/trace.go:171","msg":"trace[613473530] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"288.35105ms","start":"2026-04-02T17:35:41.002839Z","end":"2026-04-02T17:35:41.29119Z","steps":["trace[613473530] 'process raft request'  (duration: 288.103934ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:41.291371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.36468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-04-02T17:35:41.291387Z","caller":"traceutil/trace.go:171","msg":"trace[1670287531] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24725; }","duration":"124.429841ms","start":"2026-04-02T17:35:41.166953Z","end":"2026-04-02T17:35:41.291383Z","steps":["trace[1670287531] 'agreement among raft nodes before linearized reading'  (duration: 124.353699ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.291617Z","caller":"traceutil/trace.go:171","msg":"trace[540127318] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"288.914816ms","start":"2026-04-02T17:35:41.002692Z","end":"2026-04-02T17:35:41.291607Z","steps":["trace[540127318] 'process raft request'  (duration: 288.230297ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.291717Z","caller":"traceutil/trace.go:171","msg":"trace[2048680203] transaction","detail":"{read_only:false; response_revision:24723; number_of_response:1; }","duration":"289.481422ms","start":"2026-04-02T17:35:41.002229Z","end":"2026-04-02T17:35:41.291711Z","steps":["trace[2048680203] 'process raft request'  (duration: 288.582687ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.291291Z","caller":"traceutil/trace.go:171","msg":"trace[1092782221] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:24725; }","duration":"160.549611ms","start":"2026-04-02T17:35:41.130731Z","end":"2026-04-02T17:35:41.291281Z","steps":["trace[1092782221] 'agreement among raft nodes before linearized reading'  (duration: 160.476779ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.446129Z","caller":"traceutil/trace.go:171","msg":"trace[107029480] linearizableReadLoop","detail":"{readStateIndex:25542; appliedIndex:25541; }","duration":"108.77217ms","start":"2026-04-02T17:35:41.337287Z","end":"2026-04-02T17:35:41.446059Z","steps":["trace[107029480] 'read index received'  (duration: 106.408447ms)","trace[107029480] 'applied index is now lower than readState.Index'  (duration: 2.361823ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:35:41.44628Z","caller":"traceutil/trace.go:171","msg":"trace[2075922704] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"148.167918ms","start":"2026-04-02T17:35:41.298067Z","end":"2026-04-02T17:35:41.446235Z","steps":["trace[2075922704] 'process raft request'  (duration: 145.751973ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:41.446438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.127539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"}
{"level":"info","ts":"2026-04-02T17:35:41.446494Z","caller":"traceutil/trace.go:171","msg":"trace[1690643792] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24727; }","duration":"109.243072ms","start":"2026-04-02T17:35:41.337237Z","end":"2026-04-02T17:35:41.44648Z","steps":["trace[1690643792] 'agreement among raft nodes before linearized reading'  (duration: 109.048597ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.786021Z","caller":"traceutil/trace.go:171","msg":"trace[2001799419] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"218.971138ms","start":"2026-04-02T17:35:41.567011Z","end":"2026-04-02T17:35:41.785983Z","steps":["trace[2001799419] 'process raft request'  (duration: 218.733742ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.799828Z","caller":"traceutil/trace.go:171","msg":"trace[714338718] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"227.126248ms","start":"2026-04-02T17:35:41.572675Z","end":"2026-04-02T17:35:41.799802Z","steps":["trace[714338718] 'process raft request'  (duration: 227.031455ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.799879Z","caller":"traceutil/trace.go:171","msg":"trace[1540057419] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"231.365812ms","start":"2026-04-02T17:35:41.568471Z","end":"2026-04-02T17:35:41.799837Z","steps":["trace[1540057419] 'process raft request'  (duration: 231.053563ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:41.800138Z","caller":"traceutil/trace.go:171","msg":"trace[1846184505] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"226.756468ms","start":"2026-04-02T17:35:41.57335Z","end":"2026-04-02T17:35:41.800106Z","steps":["trace[1846184505] 'process raft request'  (duration: 226.406669ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:42.330388Z","caller":"traceutil/trace.go:171","msg":"trace[733863800] linearizableReadLoop","detail":"{readStateIndex:25548; appliedIndex:25547; }","duration":"166.017346ms","start":"2026-04-02T17:35:42.164349Z","end":"2026-04-02T17:35:42.330366Z","steps":["trace[733863800] 'read index received'  (duration: 165.910563ms)","trace[733863800] 'applied index is now lower than readState.Index'  (duration: 105.623µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:35:42.330504Z","caller":"traceutil/trace.go:171","msg":"trace[406667735] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"299.201911ms","start":"2026-04-02T17:35:42.03124Z","end":"2026-04-02T17:35:42.330442Z","steps":["trace[406667735] 'process raft request'  (duration: 298.942254ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:42.330621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.255774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:35:42.330665Z","caller":"traceutil/trace.go:171","msg":"trace[1883859367] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24733; }","duration":"166.378796ms","start":"2026-04-02T17:35:42.164274Z","end":"2026-04-02T17:35:42.330653Z","steps":["trace[1883859367] 'agreement among raft nodes before linearized reading'  (duration: 166.240712ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:42.529985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.462877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-02T17:35:42.530101Z","caller":"traceutil/trace.go:171","msg":"trace[466612582] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24733; }","duration":"100.630611ms","start":"2026-04-02T17:35:42.429442Z","end":"2026-04-02T17:35:42.530072Z","steps":["trace[466612582] 'count revisions from in-memory index tree'  (duration: 100.371245ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:42.822501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.681909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:35:42.822645Z","caller":"traceutil/trace.go:171","msg":"trace[2102266021] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24734; }","duration":"171.902224ms","start":"2026-04-02T17:35:42.650713Z","end":"2026-04-02T17:35:42.822615Z","steps":["trace[2102266021] 'count revisions from in-memory index tree'  (duration: 171.544425ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:43.002364Z","caller":"traceutil/trace.go:171","msg":"trace[1869399600] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"127.366279ms","start":"2026-04-02T17:35:42.874961Z","end":"2026-04-02T17:35:43.002327Z","steps":["trace[1869399600] 'process raft request'  (duration: 127.116062ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:43.305063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.520071ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945873515 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24722 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:35:43.305186Z","caller":"traceutil/trace.go:171","msg":"trace[2007672417] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"300.23881ms","start":"2026-04-02T17:35:43.004925Z","end":"2026-04-02T17:35:43.305164Z","steps":["trace[2007672417] 'process raft request'  (duration: 35.505993ms)","trace[2007672417] 'compare'  (duration: 264.016388ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:35:43.305255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:43.004905Z","time spent":"300.315842ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:24722 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-02T17:35:43.317711Z","caller":"traceutil/trace.go:171","msg":"trace[782999584] transaction","detail":"{read_only:false; response_revision:24738; number_of_response:1; }","duration":"249.101337ms","start":"2026-04-02T17:35:43.068577Z","end":"2026-04-02T17:35:43.317679Z","steps":["trace[782999584] 'process raft request'  (duration: 248.881171ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:43.67173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.193897ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945873519 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24725 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:35:43.671967Z","caller":"traceutil/trace.go:171","msg":"trace[1268528659] transaction","detail":"{read_only:false; response_revision:24739; number_of_response:1; }","duration":"349.42434ms","start":"2026-04-02T17:35:43.322508Z","end":"2026-04-02T17:35:43.671932Z","steps":["trace[1268528659] 'process raft request'  (duration: 136.902755ms)","trace[1268528659] 'compare'  (duration: 212.035242ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:35:43.672151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:43.322446Z","time spent":"349.607075ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24725 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-04-02T17:35:43.672198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.462002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-04-02T17:35:43.67224Z","caller":"traceutil/trace.go:171","msg":"trace[1526298315] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24741; }","duration":"219.575115ms","start":"2026-04-02T17:35:43.452652Z","end":"2026-04-02T17:35:43.672227Z","steps":["trace[1526298315] 'agreement among raft nodes before linearized reading'  (duration: 219.37481ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:43.672222Z","caller":"traceutil/trace.go:171","msg":"trace[277209526] transaction","detail":"{read_only:false; response_revision:24740; number_of_response:1; }","duration":"349.523123ms","start":"2026-04-02T17:35:43.322622Z","end":"2026-04-02T17:35:43.672145Z","steps":["trace[277209526] 'process raft request'  (duration: 349.249295ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:35:43.672222Z","caller":"traceutil/trace.go:171","msg":"trace[1784676467] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"349.073381ms","start":"2026-04-02T17:35:43.323103Z","end":"2026-04-02T17:35:43.672177Z","steps":["trace[1784676467] 'process raft request'  (duration: 348.826314ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:35:43.672403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:43.322591Z","time spent":"349.721718ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24724 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-04-02T17:35:43.672514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:35:43.323084Z","time spent":"349.307578ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24723 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-04-02T17:35:43.671991Z","caller":"traceutil/trace.go:171","msg":"trace[1188149512] linearizableReadLoop","detail":"{readStateIndex:25556; appliedIndex:25553; }","duration":"219.210855ms","start":"2026-04-02T17:35:43.452761Z","end":"2026-04-02T17:35:43.671971Z","steps":["trace[1188149512] 'read index received'  (duration: 6.616818ms)","trace[1188149512] 'applied index is now lower than readState.Index'  (duration: 212.592627ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:36:06.032348Z","caller":"traceutil/trace.go:171","msg":"trace[667316311] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"144.141929ms","start":"2026-04-02T17:36:05.888158Z","end":"2026-04-02T17:36:06.0323Z","steps":["trace[667316311] 'process raft request'  (duration: 104.782202ms)","trace[667316311] 'compare'  (duration: 38.989107ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:36:06.032848Z","caller":"traceutil/trace.go:171","msg":"trace[646763879] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"144.055377ms","start":"2026-04-02T17:36:05.888739Z","end":"2026-04-02T17:36:06.032794Z","steps":["trace[646763879] 'process raft request'  (duration: 143.493672ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:36:06.032944Z","caller":"traceutil/trace.go:171","msg":"trace[691445546] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"143.758169ms","start":"2026-04-02T17:36:05.889124Z","end":"2026-04-02T17:36:06.032882Z","steps":["trace[691445546] 'process raft request'  (duration: 143.603595ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:36:11.607363Z","caller":"traceutil/trace.go:171","msg":"trace[733521326] linearizableReadLoop","detail":"{readStateIndex:25768; appliedIndex:25767; }","duration":"168.065391ms","start":"2026-04-02T17:36:11.439268Z","end":"2026-04-02T17:36:11.607333Z","steps":["trace[733521326] 'read index received'  (duration: 167.709472ms)","trace[733521326] 'applied index is now lower than readState.Index'  (duration: 354.749µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:36:11.607463Z","caller":"traceutil/trace.go:171","msg":"trace[1394222641] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"172.10083ms","start":"2026-04-02T17:36:11.435285Z","end":"2026-04-02T17:36:11.607386Z","steps":["trace[1394222641] 'process raft request'  (duration: 171.813852ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:36:11.607636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.350269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"}
{"level":"info","ts":"2026-04-02T17:36:11.607722Z","caller":"traceutil/trace.go:171","msg":"trace[1472308185] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24946; }","duration":"168.503433ms","start":"2026-04-02T17:36:11.439204Z","end":"2026-04-02T17:36:11.607707Z","steps":["trace[1472308185] 'agreement among raft nodes before linearized reading'  (duration: 168.249697ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:36:14.899516Z","caller":"traceutil/trace.go:171","msg":"trace[111406332] linearizableReadLoop","detail":"{readStateIndex:25794; appliedIndex:25793; }","duration":"114.920795ms","start":"2026-04-02T17:36:14.78457Z","end":"2026-04-02T17:36:14.89949Z","steps":["trace[111406332] 'read index received'  (duration: 114.683018ms)","trace[111406332] 'applied index is now lower than readState.Index'  (duration: 236.337µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:36:14.89957Z","caller":"traceutil/trace.go:171","msg":"trace[77466942] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"123.836505ms","start":"2026-04-02T17:36:14.775699Z","end":"2026-04-02T17:36:14.899536Z","steps":["trace[77466942] 'process raft request'  (duration: 123.585938ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:36:14.899812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.214273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"}
{"level":"info","ts":"2026-04-02T17:36:14.899861Z","caller":"traceutil/trace.go:171","msg":"trace[656399018] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24972; }","duration":"115.286825ms","start":"2026-04-02T17:36:14.784562Z","end":"2026-04-02T17:36:14.899849Z","steps":["trace[656399018] 'agreement among raft nodes before linearized reading'  (duration: 115.034268ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:36:15.084122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.026627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:36:15.084214Z","caller":"traceutil/trace.go:171","msg":"trace[41034612] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24972; }","duration":"112.175881ms","start":"2026-04-02T17:36:14.972019Z","end":"2026-04-02T17:36:15.084195Z","steps":["trace[41034612] 'range keys from in-memory index tree'  (duration: 111.887454ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:36:15.353504Z","caller":"traceutil/trace.go:171","msg":"trace[1800299321] linearizableReadLoop","detail":"{readStateIndex:25795; appliedIndex:25794; }","duration":"199.927628ms","start":"2026-04-02T17:36:15.153549Z","end":"2026-04-02T17:36:15.353477Z","steps":["trace[1800299321] 'read index received'  (duration: 199.640109ms)","trace[1800299321] 'applied index is now lower than readState.Index'  (duration: 286.569µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:36:15.353579Z","caller":"traceutil/trace.go:171","msg":"trace[1575802317] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"214.789196ms","start":"2026-04-02T17:36:15.138738Z","end":"2026-04-02T17:36:15.353527Z","steps":["trace[1575802317] 'process raft request'  (duration: 214.54456ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:36:15.353696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.121744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:36:15.353782Z","caller":"traceutil/trace.go:171","msg":"trace[136687517] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:24973; }","duration":"200.278798ms","start":"2026-04-02T17:36:15.153486Z","end":"2026-04-02T17:36:15.353764Z","steps":["trace[136687517] 'agreement among raft nodes before linearized reading'  (duration: 200.112273ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:36:32.450861Z","caller":"traceutil/trace.go:171","msg":"trace[304692314] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"151.24582ms","start":"2026-04-02T17:36:32.299569Z","end":"2026-04-02T17:36:32.450815Z","steps":["trace[304692314] 'process raft request'  (duration: 150.993263ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:36:32.650406Z","caller":"traceutil/trace.go:171","msg":"trace[722306276] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"111.795071ms","start":"2026-04-02T17:36:32.538582Z","end":"2026-04-02T17:36:32.650377Z","steps":["trace[722306276] 'process raft request'  (duration: 13.376959ms)","trace[722306276] 'compare'  (duration: 97.9635ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:36:32.767028Z","caller":"traceutil/trace.go:171","msg":"trace[1512005861] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"108.671228ms","start":"2026-04-02T17:36:32.658311Z","end":"2026-04-02T17:36:32.766982Z","steps":["trace[1512005861] 'process raft request'  (duration: 95.438302ms)","trace[1512005861] 'compare'  (duration: 13.048211ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:15.664182Z","caller":"traceutil/trace.go:171","msg":"trace[798606624] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"159.038758ms","start":"2026-04-02T17:37:15.50511Z","end":"2026-04-02T17:37:15.664149Z","steps":["trace[798606624] 'process raft request'  (duration: 158.851163ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:15.968518Z","caller":"traceutil/trace.go:171","msg":"trace[1782236641] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"100.673193ms","start":"2026-04-02T17:37:15.867813Z","end":"2026-04-02T17:37:15.968486Z","steps":["trace[1782236641] 'process raft request'  (duration: 100.070407ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:16.386588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.494196ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945875446 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:25514 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:37:16.386746Z","caller":"traceutil/trace.go:171","msg":"trace[1391978710] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"153.889282ms","start":"2026-04-02T17:37:16.232833Z","end":"2026-04-02T17:37:16.386722Z","steps":["trace[1391978710] 'process raft request'  (duration: 40.146209ms)","trace[1391978710] 'compare'  (duration: 113.279031ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:37:16.675793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.367225ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945875447 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:25513 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:37:16.67612Z","caller":"traceutil/trace.go:171","msg":"trace[442782504] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"354.149498ms","start":"2026-04-02T17:37:16.321939Z","end":"2026-04-02T17:37:16.676089Z","steps":["trace[442782504] 'process raft request'  (duration: 354.030715ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:16.676131Z","caller":"traceutil/trace.go:171","msg":"trace[1730613905] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"437.011192ms","start":"2026-04-02T17:37:16.239077Z","end":"2026-04-02T17:37:16.676088Z","steps":["trace[1730613905] 'process raft request'  (duration: 436.807586ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:16.676274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.321916Z","time spent":"354.276052ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:25516 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-04-02T17:37:16.676331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.239064Z","time spent":"437.164196ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:25515 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-04-02T17:37:16.676425Z","caller":"traceutil/trace.go:171","msg":"trace[1949947773] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"436.298633ms","start":"2026-04-02T17:37:16.240115Z","end":"2026-04-02T17:37:16.676413Z","steps":["trace[1949947773] 'process raft request'  (duration: 435.810761ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:16.676471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.2401Z","time spent":"436.346864ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:25512 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-04-02T17:37:16.676665Z","caller":"traceutil/trace.go:171","msg":"trace[1373813071] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"441.965156ms","start":"2026-04-02T17:37:16.234678Z","end":"2026-04-02T17:37:16.676643Z","steps":["trace[1373813071] 'process raft request'  (duration: 283.659155ms)","trace[1373813071] 'compare'  (duration: 156.937153ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:16.67673Z","caller":"traceutil/trace.go:171","msg":"trace[1078547520] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"350.993723ms","start":"2026-04-02T17:37:16.325728Z","end":"2026-04-02T17:37:16.676722Z","steps":["trace[1078547520] 'process raft request'  (duration: 350.281134ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:16.676737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.234643Z","time spent":"442.057358ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:25513 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-04-02T17:37:16.676777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.325706Z","time spent":"351.047474ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:25517 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"info","ts":"2026-04-02T17:37:16.676919Z","caller":"traceutil/trace.go:171","msg":"trace[479203503] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"347.533049ms","start":"2026-04-02T17:37:16.329334Z","end":"2026-04-02T17:37:16.676867Z","steps":["trace[479203503] 'process raft request'  (duration: 346.702837ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:16.677125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.329315Z","time spent":"347.699884ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:25518 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-04-02T17:37:17.043895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.803754ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945875453 > lease_revoke:<id:35db9d4f1852dceb>","response":"size:28"}
{"level":"info","ts":"2026-04-02T17:37:17.04402Z","caller":"traceutil/trace.go:171","msg":"trace[1902352125] linearizableReadLoop","detail":"{readStateIndex:26369; appliedIndex:26362; }","duration":"562.119432ms","start":"2026-04-02T17:37:16.481879Z","end":"2026-04-02T17:37:17.043999Z","steps":["trace[1902352125] 'read index received'  (duration: 36.452109ms)","trace[1902352125] 'applied index is now lower than readState.Index'  (duration: 525.665283ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:37:17.044465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.565823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"}
{"level":"info","ts":"2026-04-02T17:37:17.044609Z","caller":"traceutil/trace.go:171","msg":"trace[582505329] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25533; }","duration":"562.762568ms","start":"2026-04-02T17:37:16.481814Z","end":"2026-04-02T17:37:17.044577Z","steps":["trace[582505329] 'agreement among raft nodes before linearized reading'  (duration: 562.429859ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:17.044658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.481786Z","time spent":"562.862401ms","remote":"127.0.0.1:50402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"info","ts":"2026-04-02T17:37:17.191631Z","caller":"traceutil/trace.go:171","msg":"trace[194535575] linearizableReadLoop","detail":"{readStateIndex:26370; appliedIndex:26369; }","duration":"147.550221ms","start":"2026-04-02T17:37:17.044051Z","end":"2026-04-02T17:37:17.191601Z","steps":["trace[194535575] 'read index received'  (duration: 147.304465ms)","trace[194535575] 'applied index is now lower than readState.Index'  (duration: 243.906µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:17.191772Z","caller":"traceutil/trace.go:171","msg":"trace[771739226] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"367.113775ms","start":"2026-04-02T17:37:16.824638Z","end":"2026-04-02T17:37:17.191752Z","steps":["trace[771739226] 'process raft request'  (duration: 366.745605ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:17.191893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.177836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-04-02T17:37:17.191908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.824604Z","time spent":"367.218138ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:25520 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"info","ts":"2026-04-02T17:37:17.191944Z","caller":"traceutil/trace.go:171","msg":"trace[267483172] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25534; }","duration":"659.26972ms","start":"2026-04-02T17:37:16.53266Z","end":"2026-04-02T17:37:17.19193Z","steps":["trace[267483172] 'agreement among raft nodes before linearized reading'  (duration: 659.159607ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:17.191939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.119154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:37:17.192048Z","caller":"traceutil/trace.go:171","msg":"trace[257857285] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25534; }","duration":"580.317748ms","start":"2026-04-02T17:37:16.611709Z","end":"2026-04-02T17:37:17.192027Z","steps":["trace[257857285] 'agreement among raft nodes before linearized reading'  (duration: 580.083172ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:17.19195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.098458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:37:17.192114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.611685Z","time spent":"580.415601ms","remote":"127.0.0.1:34290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "}
{"level":"warn","ts":"2026-04-02T17:37:17.1921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.566509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"warn","ts":"2026-04-02T17:37:17.192173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.466004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"}
{"level":"info","ts":"2026-04-02T17:37:17.192206Z","caller":"traceutil/trace.go:171","msg":"trace[667007487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25534; }","duration":"503.706753ms","start":"2026-04-02T17:37:16.688474Z","end":"2026-04-02T17:37:17.192181Z","steps":["trace[667007487] 'agreement among raft nodes before linearized reading'  (duration: 503.460276ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:17.192225Z","caller":"traceutil/trace.go:171","msg":"trace[387639957] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25534; }","duration":"142.547536ms","start":"2026-04-02T17:37:17.049662Z","end":"2026-04-02T17:37:17.19221Z","steps":["trace[387639957] 'agreement among raft nodes before linearized reading'  (duration: 142.368032ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:17.192259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.688454Z","time spent":"503.796174ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-02T17:37:17.192167Z","caller":"traceutil/trace.go:171","msg":"trace[1569674531] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25534; }","duration":"645.327134ms","start":"2026-04-02T17:37:16.546798Z","end":"2026-04-02T17:37:17.192125Z","steps":["trace[1569674531] 'agreement among raft nodes before linearized reading'  (duration: 645.014866ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:17.192365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.54677Z","time spent":"645.54012ms","remote":"127.0.0.1:53398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "}
{"level":"warn","ts":"2026-04-02T17:37:17.191897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.167712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:37:17.192439Z","caller":"traceutil/trace.go:171","msg":"trace[964281789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25534; }","duration":"248.82605ms","start":"2026-04-02T17:37:16.943598Z","end":"2026-04-02T17:37:17.192425Z","steps":["trace[964281789] 'agreement among raft nodes before linearized reading'  (duration: 248.09037ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:17.191993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:16.532649Z","time spent":"659.330291ms","remote":"127.0.0.1:51480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "}
{"level":"info","ts":"2026-04-02T17:37:17.343381Z","caller":"traceutil/trace.go:171","msg":"trace[1909300615] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"145.002693ms","start":"2026-04-02T17:37:17.198318Z","end":"2026-04-02T17:37:17.34332Z","steps":["trace[1909300615] 'process raft request'  (duration: 94.471937ms)","trace[1909300615] 'compare'  (duration: 50.355612ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:17.844979Z","caller":"traceutil/trace.go:171","msg":"trace[308794763] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"126.949098ms","start":"2026-04-02T17:37:17.717997Z","end":"2026-04-02T17:37:17.844946Z","steps":["trace[308794763] 'process raft request'  (duration: 126.771713ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:18.262408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.525394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:37:18.262489Z","caller":"traceutil/trace.go:171","msg":"trace[1654590264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25537; }","duration":"317.661858ms","start":"2026-04-02T17:37:17.944806Z","end":"2026-04-02T17:37:18.262468Z","steps":["trace[1654590264] 'range keys from in-memory index tree'  (duration: 317.435292ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:18.26252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:37:17.94478Z","time spent":"317.73163ms","remote":"127.0.0.1:50224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-04-02T17:37:18.263162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.703898ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945875473 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:25525 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:37:18.263305Z","caller":"traceutil/trace.go:171","msg":"trace[1039737623] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"288.339541ms","start":"2026-04-02T17:37:17.97494Z","end":"2026-04-02T17:37:18.26328Z","steps":["trace[1039737623] 'process raft request'  (duration: 122.375206ms)","trace[1039737623] 'compare'  (duration: 165.406701ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:37:18.506782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.753643ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945875474 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:25526 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:440 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:37:18.506908Z","caller":"traceutil/trace.go:171","msg":"trace[1459833938] linearizableReadLoop","detail":"{readStateIndex:26375; appliedIndex:26374; }","duration":"242.656583ms","start":"2026-04-02T17:37:18.264233Z","end":"2026-04-02T17:37:18.50689Z","steps":["trace[1459833938] 'read index received'  (duration: 99.724566ms)","trace[1459833938] 'applied index is now lower than readState.Index'  (duration: 142.930657ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:18.507004Z","caller":"traceutil/trace.go:171","msg":"trace[709636703] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"273.992266ms","start":"2026-04-02T17:37:18.233Z","end":"2026-04-02T17:37:18.506993Z","steps":["trace[709636703] 'process raft request'  (duration: 130.928475ms)","trace[709636703] 'compare'  (duration: 142.606439ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:37:18.507135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.87211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:37:18.507235Z","caller":"traceutil/trace.go:171","msg":"trace[1614988840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25539; }","duration":"242.989263ms","start":"2026-04-02T17:37:18.264223Z","end":"2026-04-02T17:37:18.507213Z","steps":["trace[1614988840] 'agreement among raft nodes before linearized reading'  (duration: 242.821128ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:18.633194Z","caller":"traceutil/trace.go:171","msg":"trace[26744074] linearizableReadLoop","detail":"{readStateIndex:26376; appliedIndex:26375; }","duration":"126.209498ms","start":"2026-04-02T17:37:18.506956Z","end":"2026-04-02T17:37:18.633165Z","steps":["trace[26744074] 'read index received'  (duration: 126.036123ms)","trace[26744074] 'applied index is now lower than readState.Index'  (duration: 171.545µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:37:18.633429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.284107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-04-02T17:37:18.633466Z","caller":"traceutil/trace.go:171","msg":"trace[706197824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25540; }","duration":"241.393261ms","start":"2026-04-02T17:37:18.392062Z","end":"2026-04-02T17:37:18.633455Z","steps":["trace[706197824] 'agreement among raft nodes before linearized reading'  (duration: 241.214816ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:18.633708Z","caller":"traceutil/trace.go:171","msg":"trace[2058861058] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"158.802662ms","start":"2026-04-02T17:37:18.474895Z","end":"2026-04-02T17:37:18.633698Z","steps":["trace[2058861058] 'process raft request'  (duration: 158.085063ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:39.603722Z","caller":"traceutil/trace.go:171","msg":"trace[433199044] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"140.214694ms","start":"2026-04-02T17:37:39.463475Z","end":"2026-04-02T17:37:39.60369Z","steps":["trace[433199044] 'process raft request'  (duration: 139.971438ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:39.779833Z","caller":"traceutil/trace.go:171","msg":"trace[1413176626] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"100.820667ms","start":"2026-04-02T17:37:39.678973Z","end":"2026-04-02T17:37:39.779794Z","steps":["trace[1413176626] 'process raft request'  (duration: 66.559697ms)","trace[1413176626] 'compare'  (duration: 33.857439ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:40.467404Z","caller":"traceutil/trace.go:171","msg":"trace[1173710265] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"123.683621ms","start":"2026-04-02T17:37:40.343697Z","end":"2026-04-02T17:37:40.467381Z","steps":["trace[1173710265] 'process raft request'  (duration: 123.537127ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:40.742738Z","caller":"traceutil/trace.go:171","msg":"trace[1444328734] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"145.108215ms","start":"2026-04-02T17:37:40.597598Z","end":"2026-04-02T17:37:40.742706Z","steps":["trace[1444328734] 'process raft request'  (duration: 144.89969ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:41.060001Z","caller":"traceutil/trace.go:171","msg":"trace[1597922262] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"223.743876ms","start":"2026-04-02T17:37:40.836222Z","end":"2026-04-02T17:37:41.059966Z","steps":["trace[1597922262] 'process raft request'  (duration: 138.903989ms)","trace[1597922262] 'compare'  (duration: 84.367365ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:41.061319Z","caller":"traceutil/trace.go:171","msg":"trace[1331391372] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"224.259471ms","start":"2026-04-02T17:37:40.837041Z","end":"2026-04-02T17:37:41.0613Z","steps":["trace[1331391372] 'process raft request'  (duration: 223.961443ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:41.061455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.666879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:37:41.061531Z","caller":"traceutil/trace.go:171","msg":"trace[656271581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25709; }","duration":"117.843293ms","start":"2026-04-02T17:37:40.943674Z","end":"2026-04-02T17:37:41.061517Z","steps":["trace[656271581] 'agreement among raft nodes before linearized reading'  (duration: 117.678259ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:41.061293Z","caller":"traceutil/trace.go:171","msg":"trace[223415744] linearizableReadLoop","detail":"{readStateIndex:26549; appliedIndex:26546; }","duration":"117.523565ms","start":"2026-04-02T17:37:40.943743Z","end":"2026-04-02T17:37:41.061266Z","steps":["trace[223415744] 'read index received'  (duration: 31.398744ms)","trace[223415744] 'applied index is now lower than readState.Index'  (duration: 86.123301ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:37:41.061443Z","caller":"traceutil/trace.go:171","msg":"trace[700936987] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"224.121256ms","start":"2026-04-02T17:37:40.837279Z","end":"2026-04-02T17:37:41.0614Z","steps":["trace[700936987] 'process raft request'  (duration: 223.929711ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:37:41.438995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.053079ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945875886 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:25696 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:37:41.439129Z","caller":"traceutil/trace.go:171","msg":"trace[1183755627] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"127.628935ms","start":"2026-04-02T17:37:41.311476Z","end":"2026-04-02T17:37:41.439105Z","steps":["trace[1183755627] 'process raft request'  (duration: 12.344041ms)","trace[1183755627] 'compare'  (duration: 114.883074ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:37:41.70768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.355858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-02T17:37:41.708048Z","caller":"traceutil/trace.go:171","msg":"trace[1263715602] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25711; }","duration":"140.780279ms","start":"2026-04-02T17:37:41.567246Z","end":"2026-04-02T17:37:41.708027Z","steps":["trace[1263715602] 'count revisions from in-memory index tree'  (duration: 140.236575ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:41.891028Z","caller":"traceutil/trace.go:171","msg":"trace[80379555] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"113.233051ms","start":"2026-04-02T17:37:41.777761Z","end":"2026-04-02T17:37:41.890994Z","steps":["trace[80379555] 'process raft request'  (duration: 111.280178ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:48.016395Z","caller":"traceutil/trace.go:171","msg":"trace[1011661765] transaction","detail":"{read_only:false; response_revision:25897; number_of_response:1; }","duration":"197.648976ms","start":"2026-04-02T17:37:47.81871Z","end":"2026-04-02T17:37:48.016359Z","steps":["trace[1011661765] 'process raft request'  (duration: 197.346668ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:48.654051Z","caller":"traceutil/trace.go:171","msg":"trace[1771631416] linearizableReadLoop","detail":"{readStateIndex:26746; appliedIndex:26745; }","duration":"151.60024ms","start":"2026-04-02T17:37:48.502416Z","end":"2026-04-02T17:37:48.654017Z","steps":["trace[1771631416] 'read index received'  (duration: 151.25153ms)","trace[1771631416] 'applied index is now lower than readState.Index'  (duration: 347.06µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:37:48.654345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.909268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:972"}
{"level":"info","ts":"2026-04-02T17:37:48.654385Z","caller":"traceutil/trace.go:171","msg":"trace[1021124030] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:25899; }","duration":"152.027061ms","start":"2026-04-02T17:37:48.502343Z","end":"2026-04-02T17:37:48.65437Z","steps":["trace[1021124030] 'agreement among raft nodes before linearized reading'  (duration: 151.794595ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:48.654848Z","caller":"traceutil/trace.go:171","msg":"trace[636950529] transaction","detail":"{read_only:false; response_revision:25899; number_of_response:1; }","duration":"156.07917ms","start":"2026-04-02T17:37:48.498753Z","end":"2026-04-02T17:37:48.654833Z","steps":["trace[636950529] 'process raft request'  (duration: 155.008142ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:50.505392Z","caller":"traceutil/trace.go:171","msg":"trace[1630801470] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"153.795519ms","start":"2026-04-02T17:37:50.351551Z","end":"2026-04-02T17:37:50.505347Z","steps":["trace[1630801470] 'process raft request'  (duration: 153.562833ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:37:57.613042Z","caller":"traceutil/trace.go:171","msg":"trace[1432089977] transaction","detail":"{read_only:false; response_revision:25994; number_of_response:1; }","duration":"107.171147ms","start":"2026-04-02T17:37:57.505848Z","end":"2026-04-02T17:37:57.61302Z","steps":["trace[1432089977] 'process raft request'  (duration: 107.019953ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:07.799942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23283}
{"level":"info","ts":"2026-04-02T17:38:07.942837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23283,"took":"137.756338ms","hash":1458533965,"current-db-size-bytes":59469824,"current-db-size":"60 MB","current-db-size-in-use-bytes":34906112,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-04-02T17:38:07.942901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1458533965,"revision":23283,"compact-revision":20075}
{"level":"warn","ts":"2026-04-02T17:38:39.247484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.011517ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945877700 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26285 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:38:39.247648Z","caller":"traceutil/trace.go:171","msg":"trace[404653752] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"185.382247ms","start":"2026-04-02T17:38:39.062242Z","end":"2026-04-02T17:38:39.247624Z","steps":["trace[404653752] 'process raft request'  (duration: 51.095902ms)","trace[404653752] 'compare'  (duration: 133.706329ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:38:39.481351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.378215ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945877701 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:26286 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:38:39.481635Z","caller":"traceutil/trace.go:171","msg":"trace[434096977] linearizableReadLoop","detail":"{readStateIndex:27163; appliedIndex:27160; }","duration":"409.434052ms","start":"2026-04-02T17:38:39.072174Z","end":"2026-04-02T17:38:39.481608Z","steps":["trace[434096977] 'read index received'  (duration: 41.253468ms)","trace[434096977] 'applied index is now lower than readState.Index'  (duration: 368.178924ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:39.481753Z","caller":"traceutil/trace.go:171","msg":"trace[1185551436] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"418.572818ms","start":"2026-04-02T17:38:39.063169Z","end":"2026-04-02T17:38:39.481742Z","steps":["trace[1185551436] 'process raft request'  (duration: 418.32038ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.481822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:39.063131Z","time spent":"418.65144ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26287 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-04-02T17:38:39.481861Z","caller":"traceutil/trace.go:171","msg":"trace[704369906] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"418.66246ms","start":"2026-04-02T17:38:39.063127Z","end":"2026-04-02T17:38:39.481789Z","steps":["trace[704369906] 'process raft request'  (duration: 292.689508ms)","trace[704369906] 'compare'  (duration: 125.108528ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:38:39.481901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.464264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:39.482028Z","caller":"traceutil/trace.go:171","msg":"trace[1683758393] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26304; }","duration":"409.632618ms","start":"2026-04-02T17:38:39.072365Z","end":"2026-04-02T17:38:39.481998Z","steps":["trace[1683758393] 'agreement among raft nodes before linearized reading'  (duration: 409.436093ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.482067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:39.063094Z","time spent":"418.866115ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:26286 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-04-02T17:38:39.482092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:39.072354Z","time spent":"409.725541ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "}
{"level":"warn","ts":"2026-04-02T17:38:39.482333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.162162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:39.482381Z","caller":"traceutil/trace.go:171","msg":"trace[1478853109] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26304; }","duration":"410.259804ms","start":"2026-04-02T17:38:39.072109Z","end":"2026-04-02T17:38:39.482369Z","steps":["trace[1478853109] 'agreement among raft nodes before linearized reading'  (duration: 410.190672ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.482418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:39.07209Z","time spent":"410.320216ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "}
{"level":"warn","ts":"2026-04-02T17:38:39.482794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.296975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:39.482856Z","caller":"traceutil/trace.go:171","msg":"trace[805897822] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26304; }","duration":"410.390928ms","start":"2026-04-02T17:38:39.07245Z","end":"2026-04-02T17:38:39.48284Z","steps":["trace[805897822] 'agreement among raft nodes before linearized reading'  (duration: 410.297575ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.482866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.364344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-04-02T17:38:39.482963Z","caller":"traceutil/trace.go:171","msg":"trace[1884213373] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26304; }","duration":"408.493836ms","start":"2026-04-02T17:38:39.07445Z","end":"2026-04-02T17:38:39.482943Z","steps":["trace[1884213373] 'agreement among raft nodes before linearized reading'  (duration: 408.293961ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.483007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:39.074439Z","time spent":"408.559599ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "}
{"level":"warn","ts":"2026-04-02T17:38:39.482898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:39.072409Z","time spent":"410.47916ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "}
{"level":"warn","ts":"2026-04-02T17:38:39.483205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.236756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:39.483251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.78283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"}
{"level":"info","ts":"2026-04-02T17:38:39.483261Z","caller":"traceutil/trace.go:171","msg":"trace[248298942] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26304; }","duration":"238.328248ms","start":"2026-04-02T17:38:39.244918Z","end":"2026-04-02T17:38:39.483247Z","steps":["trace[248298942] 'agreement among raft nodes before linearized reading'  (duration: 238.243986ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:39.48329Z","caller":"traceutil/trace.go:171","msg":"trace[1962258192] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26304; }","duration":"270.898493ms","start":"2026-04-02T17:38:39.212382Z","end":"2026-04-02T17:38:39.48328Z","steps":["trace[1962258192] 'agreement among raft nodes before linearized reading'  (duration: 270.78182ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.483296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.942069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-04-02T17:38:39.483365Z","caller":"traceutil/trace.go:171","msg":"trace[899544487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26304; }","duration":"333.088632ms","start":"2026-04-02T17:38:39.150259Z","end":"2026-04-02T17:38:39.483348Z","steps":["trace[899544487] 'agreement among raft nodes before linearized reading'  (duration: 332.909537ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.483406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:39.150229Z","time spent":"333.169284ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-04-02T17:38:39.725798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.187321ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945877708 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:26292 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:38:39.726048Z","caller":"traceutil/trace.go:171","msg":"trace[1965263182] transaction","detail":"{read_only:false; response_revision:26306; number_of_response:1; }","duration":"233.316645ms","start":"2026-04-02T17:38:39.49269Z","end":"2026-04-02T17:38:39.726006Z","steps":["trace[1965263182] 'process raft request'  (duration: 126.58724ms)","trace[1965263182] 'compare'  (duration: 105.972954ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:39.726107Z","caller":"traceutil/trace.go:171","msg":"trace[1866713826] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"233.314864ms","start":"2026-04-02T17:38:39.49278Z","end":"2026-04-02T17:38:39.726095Z","steps":["trace[1866713826] 'process raft request'  (duration: 233.14977ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:39.726276Z","caller":"traceutil/trace.go:171","msg":"trace[759749875] linearizableReadLoop","detail":"{readStateIndex:27167; appliedIndex:27163; }","duration":"233.154629ms","start":"2026-04-02T17:38:39.493093Z","end":"2026-04-02T17:38:39.726248Z","steps":["trace[759749875] 'read index received'  (duration: 52.709044ms)","trace[759749875] 'applied index is now lower than readState.Index'  (duration: 180.444115ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:39.726347Z","caller":"traceutil/trace.go:171","msg":"trace[1706837533] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"233.493639ms","start":"2026-04-02T17:38:39.492819Z","end":"2026-04-02T17:38:39.726313Z","steps":["trace[1706837533] 'process raft request'  (duration: 233.227522ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.726562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.452077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18368"}
{"level":"warn","ts":"2026-04-02T17:38:39.726572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.364317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:39.726613Z","caller":"traceutil/trace.go:171","msg":"trace[915560469] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26308; }","duration":"233.53535ms","start":"2026-04-02T17:38:39.493066Z","end":"2026-04-02T17:38:39.726601Z","steps":["trace[915560469] 'agreement among raft nodes before linearized reading'  (duration: 233.325394ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:39.726629Z","caller":"traceutil/trace.go:171","msg":"trace[1496010292] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26308; }","duration":"229.433709ms","start":"2026-04-02T17:38:39.497176Z","end":"2026-04-02T17:38:39.72661Z","steps":["trace[1496010292] 'agreement among raft nodes before linearized reading'  (duration: 229.334816ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.726639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.443867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:39.72669Z","caller":"traceutil/trace.go:171","msg":"trace[1583966318] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:26308; }","duration":"119.56063ms","start":"2026-04-02T17:38:39.607112Z","end":"2026-04-02T17:38:39.726673Z","steps":["trace[1583966318] 'agreement among raft nodes before linearized reading'  (duration: 119.441906ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:39.727156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.489255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"}
{"level":"info","ts":"2026-04-02T17:38:39.727302Z","caller":"traceutil/trace.go:171","msg":"trace[1492859535] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:26308; }","duration":"142.778194ms","start":"2026-04-02T17:38:39.584496Z","end":"2026-04-02T17:38:39.727274Z","steps":["trace[1492859535] 'agreement among raft nodes before linearized reading'  (duration: 142.217159ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:40.002828Z","caller":"traceutil/trace.go:171","msg":"trace[1988667900] linearizableReadLoop","detail":"{readStateIndex:27168; appliedIndex:27167; }","duration":"211.783276ms","start":"2026-04-02T17:38:39.79101Z","end":"2026-04-02T17:38:40.002793Z","steps":["trace[1988667900] 'read index received'  (duration: 211.462326ms)","trace[1988667900] 'applied index is now lower than readState.Index'  (duration: 319.76µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:40.003005Z","caller":"traceutil/trace.go:171","msg":"trace[1090716149] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"233.327515ms","start":"2026-04-02T17:38:39.769662Z","end":"2026-04-02T17:38:40.002989Z","steps":["trace[1090716149] 'process raft request'  (duration: 232.894513ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:40.003312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.465188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:40.003318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.237549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-02T17:38:40.003445Z","caller":"traceutil/trace.go:171","msg":"trace[668527352] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26309; }","duration":"211.675703ms","start":"2026-04-02T17:38:39.791739Z","end":"2026-04-02T17:38:40.003415Z","steps":["trace[668527352] 'agreement among raft nodes before linearized reading'  (duration: 211.418367ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:40.00353Z","caller":"traceutil/trace.go:171","msg":"trace[1337671623] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:26309; }","duration":"212.526236ms","start":"2026-04-02T17:38:39.790972Z","end":"2026-04-02T17:38:40.003498Z","steps":["trace[1337671623] 'agreement among raft nodes before linearized reading'  (duration: 212.163116ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:40.432878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.603137ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945877727 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:26297 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:506 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:38:40.432997Z","caller":"traceutil/trace.go:171","msg":"trace[1403264004] linearizableReadLoop","detail":"{readStateIndex:27170; appliedIndex:27169; }","duration":"137.620925ms","start":"2026-04-02T17:38:40.295356Z","end":"2026-04-02T17:38:40.432977Z","steps":["trace[1403264004] 'read index received'  (duration: 694.389µs)","trace[1403264004] 'applied index is now lower than readState.Index'  (duration: 136.925136ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:40.433112Z","caller":"traceutil/trace.go:171","msg":"trace[832633551] transaction","detail":"{read_only:false; response_revision:26311; number_of_response:1; }","duration":"165.565324ms","start":"2026-04-02T17:38:40.267532Z","end":"2026-04-02T17:38:40.433098Z","steps":["trace[832633551] 'process raft request'  (duration: 28.593937ms)","trace[832633551] 'compare'  (duration: 136.33659ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:38:40.433275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:40.433356Z","caller":"traceutil/trace.go:171","msg":"trace[482148235] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26311; }","duration":"137.210633ms","start":"2026-04-02T17:38:40.296126Z","end":"2026-04-02T17:38:40.433336Z","steps":["trace[482148235] 'agreement among raft nodes before linearized reading'  (duration: 137.06619ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:40.433648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.305892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-04-02T17:38:40.43369Z","caller":"traceutil/trace.go:171","msg":"trace[1352848911] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26311; }","duration":"138.375704ms","start":"2026-04-02T17:38:40.295302Z","end":"2026-04-02T17:38:40.433677Z","steps":["trace[1352848911] 'agreement among raft nodes before linearized reading'  (duration: 138.19481ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.001371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.460016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-02T17:38:41.001556Z","caller":"traceutil/trace.go:171","msg":"trace[1161415175] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:26313; }","duration":"167.670852ms","start":"2026-04-02T17:38:40.833791Z","end":"2026-04-02T17:38:41.001462Z","steps":["trace[1161415175] 'count revisions from in-memory index tree'  (duration: 167.348253ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:41.25669Z","caller":"traceutil/trace.go:171","msg":"trace[1578507108] linearizableReadLoop","detail":"{readStateIndex:27173; appliedIndex:27172; }","duration":"311.568474ms","start":"2026-04-02T17:38:40.945093Z","end":"2026-04-02T17:38:41.256661Z","steps":["trace[1578507108] 'read index received'  (duration: 311.384589ms)","trace[1578507108] 'applied index is now lower than readState.Index'  (duration: 182.335µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:41.256805Z","caller":"traceutil/trace.go:171","msg":"trace[1149100506] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"375.596833ms","start":"2026-04-02T17:38:40.881168Z","end":"2026-04-02T17:38:41.256764Z","steps":["trace[1149100506] 'process raft request'  (duration: 375.289575ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.256898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.78233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:41.256988Z","caller":"traceutil/trace.go:171","msg":"trace[1873921106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26314; }","duration":"311.952434ms","start":"2026-04-02T17:38:40.945014Z","end":"2026-04-02T17:38:41.256967Z","steps":["trace[1873921106] 'agreement among raft nodes before linearized reading'  (duration: 311.762069ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:40.881132Z","time spent":"375.758498ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26300 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-02T17:38:41.257049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:40.944982Z","time spent":"312.059577ms","remote":"127.0.0.1:50224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-02T17:38:41.2849Z","caller":"traceutil/trace.go:171","msg":"trace[941459640] transaction","detail":"{read_only:false; response_revision:26315; number_of_response:1; }","duration":"323.810692ms","start":"2026-04-02T17:38:40.961033Z","end":"2026-04-02T17:38:41.284844Z","steps":["trace[941459640] 'process raft request'  (duration: 323.581436ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.285216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:40.960995Z","time spent":"324.009237ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:26301 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"warn","ts":"2026-04-02T17:38:41.285349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.05077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:140"}
{"level":"info","ts":"2026-04-02T17:38:41.285393Z","caller":"traceutil/trace.go:171","msg":"trace[1436193702] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:26316; }","duration":"308.093881ms","start":"2026-04-02T17:38:40.977287Z","end":"2026-04-02T17:38:41.285381Z","steps":["trace[1436193702] 'agreement among raft nodes before linearized reading'  (duration: 307.950068ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.285414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:40.977229Z","time spent":"308.180273ms","remote":"127.0.0.1:50250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.229\" "}
{"level":"warn","ts":"2026-04-02T17:38:41.552586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.870389ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945877740 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:35db9d4f1852e6eb>","response":"size:40"}
{"level":"info","ts":"2026-04-02T17:38:41.553132Z","caller":"traceutil/trace.go:171","msg":"trace[454626306] linearizableReadLoop","detail":"{readStateIndex:27176; appliedIndex:27175; }","duration":"176.464957ms","start":"2026-04-02T17:38:41.376636Z","end":"2026-04-02T17:38:41.553101Z","steps":["trace[454626306] 'read index received'  (duration: 50.033123ms)","trace[454626306] 'applied index is now lower than readState.Index'  (duration: 126.428344ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:38:41.553474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.808707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"}
{"level":"info","ts":"2026-04-02T17:38:41.553523Z","caller":"traceutil/trace.go:171","msg":"trace[332733861] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26318; }","duration":"176.919569ms","start":"2026-04-02T17:38:41.376589Z","end":"2026-04-02T17:38:41.553509Z","steps":["trace[332733861] 'agreement among raft nodes before linearized reading'  (duration: 176.675052ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.553493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.043971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-02T17:38:41.553602Z","caller":"traceutil/trace.go:171","msg":"trace[711255462] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:26318; }","duration":"146.228276ms","start":"2026-04-02T17:38:41.407346Z","end":"2026-04-02T17:38:41.553574Z","steps":["trace[711255462] 'agreement among raft nodes before linearized reading'  (duration: 146.02488ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:41.8748Z","caller":"traceutil/trace.go:171","msg":"trace[749762641] linearizableReadLoop","detail":"{readStateIndex:27180; appliedIndex:27179; }","duration":"272.388762ms","start":"2026-04-02T17:38:41.602382Z","end":"2026-04-02T17:38:41.87477Z","steps":["trace[749762641] 'read index received'  (duration: 272.147325ms)","trace[749762641] 'applied index is now lower than readState.Index'  (duration: 240.067µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:41.875034Z","caller":"traceutil/trace.go:171","msg":"trace[1524973754] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"302.266055ms","start":"2026-04-02T17:38:41.572724Z","end":"2026-04-02T17:38:41.87499Z","steps":["trace[1524973754] 'process raft request'  (duration: 301.885164ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.875123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.251563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:41.875216Z","caller":"traceutil/trace.go:171","msg":"trace[812591711] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26320; }","duration":"131.412198ms","start":"2026-04-02T17:38:41.743782Z","end":"2026-04-02T17:38:41.875195Z","steps":["trace[812591711] 'agreement among raft nodes before linearized reading'  (duration: 131.251794ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.875255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.784893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"warn","ts":"2026-04-02T17:38:41.875273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.012307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"warn","ts":"2026-04-02T17:38:41.87527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.601934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:41.875321Z","caller":"traceutil/trace.go:171","msg":"trace[1372016376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26320; }","duration":"142.877836ms","start":"2026-04-02T17:38:41.732424Z","end":"2026-04-02T17:38:41.875302Z","steps":["trace[1372016376] 'agreement among raft nodes before linearized reading'  (duration: 142.64974ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.875301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.055739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:41.875367Z","caller":"traceutil/trace.go:171","msg":"trace[212241188] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26320; }","duration":"131.140631ms","start":"2026-04-02T17:38:41.744205Z","end":"2026-04-02T17:38:41.875345Z","steps":["trace[212241188] 'agreement among raft nodes before linearized reading'  (duration: 130.852773ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:41.875397Z","caller":"traceutil/trace.go:171","msg":"trace[709307092] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26320; }","duration":"131.767117ms","start":"2026-04-02T17:38:41.743598Z","end":"2026-04-02T17:38:41.875365Z","steps":["trace[709307092] 'agreement among raft nodes before linearized reading'  (duration: 131.511831ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:41.87543Z","caller":"traceutil/trace.go:171","msg":"trace[371584994] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26320; }","duration":"131.216512ms","start":"2026-04-02T17:38:41.744183Z","end":"2026-04-02T17:38:41.8754Z","steps":["trace[371584994] 'agreement among raft nodes before linearized reading'  (duration: 130.979476ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.875387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:41.572706Z","time spent":"302.44246ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:26305 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-04-02T17:38:41.875514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.156723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"info","ts":"2026-04-02T17:38:41.875647Z","caller":"traceutil/trace.go:171","msg":"trace[496792276] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26320; }","duration":"143.344288ms","start":"2026-04-02T17:38:41.732254Z","end":"2026-04-02T17:38:41.875599Z","steps":["trace[496792276] 'agreement among raft nodes before linearized reading'  (duration: 142.963038ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.875722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.686695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-04-02T17:38:41.875763Z","caller":"traceutil/trace.go:171","msg":"trace[129818183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26320; }","duration":"144.778777ms","start":"2026-04-02T17:38:41.730972Z","end":"2026-04-02T17:38:41.875751Z","steps":["trace[129818183] 'agreement among raft nodes before linearized reading'  (duration: 144.030237ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:41.876796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.394215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"}
{"level":"info","ts":"2026-04-02T17:38:41.876853Z","caller":"traceutil/trace.go:171","msg":"trace[1433416837] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26320; }","duration":"274.499748ms","start":"2026-04-02T17:38:41.602341Z","end":"2026-04-02T17:38:41.876841Z","steps":["trace[1433416837] 'agreement among raft nodes before linearized reading'  (duration: 272.579877ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:42.307012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.781353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:42.307101Z","caller":"traceutil/trace.go:171","msg":"trace[2048318218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26323; }","duration":"361.924816ms","start":"2026-04-02T17:38:41.945155Z","end":"2026-04-02T17:38:42.30708Z","steps":["trace[2048318218] 'range keys from in-memory index tree'  (duration: 361.653519ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:42.307137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:41.945138Z","time spent":"361.990688ms","remote":"127.0.0.1:50224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-04-02T17:38:42.307208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.152932ms","expected-duration":"100ms","prefix":"","request":"header:<ID:3880868466945877758 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:26309 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-04-02T17:38:42.307331Z","caller":"traceutil/trace.go:171","msg":"trace[97601300] linearizableReadLoop","detail":"{readStateIndex:27184; appliedIndex:27183; }","duration":"144.894699ms","start":"2026-04-02T17:38:42.162416Z","end":"2026-04-02T17:38:42.307311Z","steps":["trace[97601300] 'read index received'  (duration: 53.711µs)","trace[97601300] 'applied index is now lower than readState.Index'  (duration: 144.839498ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:42.307865Z","caller":"traceutil/trace.go:171","msg":"trace[573894522] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"297.706821ms","start":"2026-04-02T17:38:42.010097Z","end":"2026-04-02T17:38:42.307804Z","steps":["trace[573894522] 'process raft request'  (duration: 146.852072ms)","trace[573894522] 'compare'  (duration: 149.854163ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:38:42.307885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.432784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-04-02T17:38:42.308193Z","caller":"traceutil/trace.go:171","msg":"trace[1314734545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26324; }","duration":"145.780923ms","start":"2026-04-02T17:38:42.16235Z","end":"2026-04-02T17:38:42.308131Z","steps":["trace[1314734545] 'agreement among raft nodes before linearized reading'  (duration: 145.248159ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:42.456073Z","caller":"traceutil/trace.go:171","msg":"trace[159796190] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"141.792366ms","start":"2026-04-02T17:38:42.314244Z","end":"2026-04-02T17:38:42.456036Z","steps":["trace[159796190] 'process raft request'  (duration: 141.57394ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:42.688203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.099237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:42.688282Z","caller":"traceutil/trace.go:171","msg":"trace[640743339] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26327; }","duration":"119.21489ms","start":"2026-04-02T17:38:42.569047Z","end":"2026-04-02T17:38:42.688262Z","steps":["trace[640743339] 'count revisions from in-memory index tree'  (duration: 119.028295ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:43.441083Z","caller":"traceutil/trace.go:171","msg":"trace[880103528] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"150.899131ms","start":"2026-04-02T17:38:43.290149Z","end":"2026-04-02T17:38:43.441048Z","steps":["trace[880103528] 'process raft request'  (duration: 119.446057ms)","trace[880103528] 'compare'  (duration: 31.148026ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:43.48591Z","caller":"traceutil/trace.go:171","msg":"trace[1759203915] linearizableReadLoop","detail":"{readStateIndex:27190; appliedIndex:27188; }","duration":"114.850403ms","start":"2026-04-02T17:38:43.371038Z","end":"2026-04-02T17:38:43.485889Z","steps":["trace[1759203915] 'read index received'  (duration: 38.466493ms)","trace[1759203915] 'applied index is now lower than readState.Index'  (duration: 76.38267ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:43.485987Z","caller":"traceutil/trace.go:171","msg":"trace[584400961] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"193.843713ms","start":"2026-04-02T17:38:43.292095Z","end":"2026-04-02T17:38:43.485939Z","steps":["trace[584400961] 'process raft request'  (duration: 193.632058ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:43.486196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.117711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-04-02T17:38:43.486255Z","caller":"traceutil/trace.go:171","msg":"trace[778952048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26330; }","duration":"115.265384ms","start":"2026-04-02T17:38:43.370975Z","end":"2026-04-02T17:38:43.48624Z","steps":["trace[778952048] 'agreement among raft nodes before linearized reading'  (duration: 115.056149ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:48.907746Z","caller":"traceutil/trace.go:171","msg":"trace[1115321835] linearizableReadLoop","detail":"{readStateIndex:27229; appliedIndex:27228; }","duration":"355.587147ms","start":"2026-04-02T17:38:48.552126Z","end":"2026-04-02T17:38:48.907713Z","steps":["trace[1115321835] 'read index received'  (duration: 355.3457ms)","trace[1115321835] 'applied index is now lower than readState.Index'  (duration: 239.837µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:48.907784Z","caller":"traceutil/trace.go:171","msg":"trace[2085166282] transaction","detail":"{read_only:false; response_revision:26368; number_of_response:1; }","duration":"375.217084ms","start":"2026-04-02T17:38:48.532528Z","end":"2026-04-02T17:38:48.907745Z","steps":["trace[2085166282] 'process raft request'  (duration: 374.863764ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:48.907991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.841513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"warn","ts":"2026-04-02T17:38:48.907997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:48.532495Z","time spent":"375.381228ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:26354 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-04-02T17:38:48.908036Z","caller":"traceutil/trace.go:171","msg":"trace[320076699] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26368; }","duration":"355.944096ms","start":"2026-04-02T17:38:48.552078Z","end":"2026-04-02T17:38:48.908022Z","steps":["trace[320076699] 'agreement among raft nodes before linearized reading'  (duration: 355.752661ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:48.908075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:48.552057Z","time spent":"356.009167ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-04-02T17:38:48.922113Z","caller":"traceutil/trace.go:171","msg":"trace[31320163] transaction","detail":"{read_only:false; response_revision:26369; number_of_response:1; }","duration":"147.653433ms","start":"2026-04-02T17:38:48.774428Z","end":"2026-04-02T17:38:48.922082Z","steps":["trace[31320163] 'process raft request'  (duration: 147.496359ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:49.845449Z","caller":"traceutil/trace.go:171","msg":"trace[1222684478] linearizableReadLoop","detail":"{readStateIndex:27234; appliedIndex:27233; }","duration":"269.182007ms","start":"2026-04-02T17:38:49.576236Z","end":"2026-04-02T17:38:49.845418Z","steps":["trace[1222684478] 'read index received'  (duration: 269.002362ms)","trace[1222684478] 'applied index is now lower than readState.Index'  (duration: 177.285µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:49.845565Z","caller":"traceutil/trace.go:171","msg":"trace[1717330165] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"336.458763ms","start":"2026-04-02T17:38:49.509068Z","end":"2026-04-02T17:38:49.845527Z","steps":["trace[1717330165] 'process raft request'  (duration: 336.112803ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.845613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.368641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:49.845649Z","caller":"traceutil/trace.go:171","msg":"trace[1342562255] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26373; }","duration":"269.512295ms","start":"2026-04-02T17:38:49.576127Z","end":"2026-04-02T17:38:49.84564Z","steps":["trace[1342562255] 'agreement among raft nodes before linearized reading'  (duration: 269.430072ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.845759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.509039Z","time spent":"336.613247ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:26359 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"info","ts":"2026-04-02T17:38:49.9273Z","caller":"traceutil/trace.go:171","msg":"trace[2009960917] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"345.631179ms","start":"2026-04-02T17:38:49.58164Z","end":"2026-04-02T17:38:49.927271Z","steps":["trace[2009960917] 'process raft request'  (duration: 343.386568ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.927529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.581616Z","time spent":"345.783612ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26360 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-04-02T17:38:49.927514Z","caller":"traceutil/trace.go:171","msg":"trace[211008193] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"339.836833ms","start":"2026-04-02T17:38:49.587649Z","end":"2026-04-02T17:38:49.927486Z","steps":["trace[211008193] 'process raft request'  (duration: 339.541666ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.927614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.915036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:49.927681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.219004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:49.927722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.587639Z","time spent":"339.957236ms","remote":"127.0.0.1:50474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:26361 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"info","ts":"2026-04-02T17:38:49.927736Z","caller":"traceutil/trace.go:171","msg":"trace[409339113] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26375; }","duration":"346.067129ms","start":"2026-04-02T17:38:49.581646Z","end":"2026-04-02T17:38:49.927713Z","steps":["trace[409339113] 'agreement among raft nodes before linearized reading'  (duration: 345.849444ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:49.927769Z","caller":"traceutil/trace.go:171","msg":"trace[1436610401] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26375; }","duration":"346.357967ms","start":"2026-04-02T17:38:49.58139Z","end":"2026-04-02T17:38:49.927748Z","steps":["trace[1436610401] 'agreement among raft nodes before linearized reading'  (duration: 346.167382ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.927777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.224298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"warn","ts":"2026-04-02T17:38:49.927793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.581638Z","time spent":"346.146242ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "}
{"level":"info","ts":"2026-04-02T17:38:49.927833Z","caller":"traceutil/trace.go:171","msg":"trace[1915887242] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26375; }","duration":"345.289359ms","start":"2026-04-02T17:38:49.582529Z","end":"2026-04-02T17:38:49.927818Z","steps":["trace[1915887242] 'agreement among raft nodes before linearized reading'  (duration: 345.092114ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.927868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.943366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:49.92788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.582525Z","time spent":"345.348021ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.927892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.540732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:49.9279Z","caller":"traceutil/trace.go:171","msg":"trace[162239048] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26375; }","duration":"345.985398ms","start":"2026-04-02T17:38:49.581905Z","end":"2026-04-02T17:38:49.92789Z","steps":["trace[162239048] 'agreement among raft nodes before linearized reading'  (duration: 345.933627ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:49.927931Z","caller":"traceutil/trace.go:171","msg":"trace[92887549] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26375; }","duration":"346.596964ms","start":"2026-04-02T17:38:49.581324Z","end":"2026-04-02T17:38:49.927921Z","steps":["trace[92887549] 'agreement among raft nodes before linearized reading'  (duration: 346.531812ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.927939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.581897Z","time spent":"346.032049ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.927968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.581292Z","time spent":"346.670546ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.928003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.161696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:49.928043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.403113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:49.928078Z","caller":"traceutil/trace.go:171","msg":"trace[708456050] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26375; }","duration":"345.444804ms","start":"2026-04-02T17:38:49.582625Z","end":"2026-04-02T17:38:49.92807Z","steps":["trace[708456050] 'agreement among raft nodes before linearized reading'  (duration: 345.391161ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.928111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.582621Z","time spent":"345.484646ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.928136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.587986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:49.928161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.4008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-04-02T17:38:49.928046Z","caller":"traceutil/trace.go:171","msg":"trace[1250763961] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26375; }","duration":"177.259899ms","start":"2026-04-02T17:38:49.750773Z","end":"2026-04-02T17:38:49.928032Z","steps":["trace[1250763961] 'agreement among raft nodes before linearized reading'  (duration: 177.192816ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:49.928193Z","caller":"traceutil/trace.go:171","msg":"trace[979314513] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26375; }","duration":"349.493593ms","start":"2026-04-02T17:38:49.57869Z","end":"2026-04-02T17:38:49.928184Z","steps":["trace[979314513] 'agreement among raft nodes before linearized reading'  (duration: 349.404031ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.928222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.578655Z","time spent":"349.558455ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.928324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.357403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:49.927832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.581382Z","time spent":"346.440789ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "}
{"level":"info","ts":"2026-04-02T17:38:49.928355Z","caller":"traceutil/trace.go:171","msg":"trace[79413856] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26375; }","duration":"348.399293ms","start":"2026-04-02T17:38:49.579948Z","end":"2026-04-02T17:38:49.928347Z","steps":["trace[79413856] 'agreement among raft nodes before linearized reading'  (duration: 348.353742ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.92838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.579941Z","time spent":"348.433775ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.928403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.144733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:49.928172Z","caller":"traceutil/trace.go:171","msg":"trace[1936685115] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26375; }","duration":"349.682357ms","start":"2026-04-02T17:38:49.578481Z","end":"2026-04-02T17:38:49.928163Z","steps":["trace[1936685115] 'agreement among raft nodes before linearized reading'  (duration: 349.633656ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:49.92848Z","caller":"traceutil/trace.go:171","msg":"trace[1853306519] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26375; }","duration":"349.260696ms","start":"2026-04-02T17:38:49.579199Z","end":"2026-04-02T17:38:49.92846Z","steps":["trace[1853306519] 'agreement among raft nodes before linearized reading'  (duration: 349.145773ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.928514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.578456Z","time spent":"350.046637ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.928537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.579176Z","time spent":"349.349919ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "}
{"level":"warn","ts":"2026-04-02T17:38:49.931266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.66869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-04-02T17:38:49.931389Z","caller":"traceutil/trace.go:171","msg":"trace[346107734] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26375; }","duration":"348.871267ms","start":"2026-04-02T17:38:49.582498Z","end":"2026-04-02T17:38:49.931369Z","steps":["trace[346107734] 'agreement among raft nodes before linearized reading'  (duration: 345.142075ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:49.931445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T17:38:49.58249Z","time spent":"348.937218ms","remote":"127.0.0.1:50452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "}
{"level":"info","ts":"2026-04-02T17:38:50.127865Z","caller":"traceutil/trace.go:171","msg":"trace[34211601] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"192.130858ms","start":"2026-04-02T17:38:49.935709Z","end":"2026-04-02T17:38:50.12784Z","steps":["trace[34211601] 'process raft request'  (duration: 192.037035ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:50.127955Z","caller":"traceutil/trace.go:171","msg":"trace[1433012376] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"192.236631ms","start":"2026-04-02T17:38:49.93568Z","end":"2026-04-02T17:38:50.127917Z","steps":["trace[1433012376] 'process raft request'  (duration: 191.862181ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:50.128093Z","caller":"traceutil/trace.go:171","msg":"trace[992878401] linearizableReadLoop","detail":"{readStateIndex:27240; appliedIndex:27237; }","duration":"191.46701ms","start":"2026-04-02T17:38:49.936593Z","end":"2026-04-02T17:38:50.12806Z","steps":["trace[992878401] 'read index received'  (duration: 42.697136ms)","trace[992878401] 'applied index is now lower than readState.Index'  (duration: 148.767684ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:50.128165Z","caller":"traceutil/trace.go:171","msg":"trace[1657585310] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"192.392225ms","start":"2026-04-02T17:38:49.935758Z","end":"2026-04-02T17:38:50.12815Z","steps":["trace[1657585310] 'process raft request'  (duration: 192.033295ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:50.12826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.644635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:50.1283Z","caller":"traceutil/trace.go:171","msg":"trace[1556665679] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26379; }","duration":"191.733207ms","start":"2026-04-02T17:38:49.936552Z","end":"2026-04-02T17:38:50.128285Z","steps":["trace[1556665679] 'agreement among raft nodes before linearized reading'  (duration: 191.623814ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:51.50932Z","caller":"traceutil/trace.go:171","msg":"trace[637044424] transaction","detail":"{read_only:false; response_revision:26387; number_of_response:1; }","duration":"178.366159ms","start":"2026-04-02T17:38:51.330923Z","end":"2026-04-02T17:38:51.50929Z","steps":["trace[637044424] 'process raft request'  (duration: 178.189255ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:51.978784Z","caller":"traceutil/trace.go:171","msg":"trace[47374887] linearizableReadLoop","detail":"{readStateIndex:27251; appliedIndex:27250; }","duration":"122.763576ms","start":"2026-04-02T17:38:51.855963Z","end":"2026-04-02T17:38:51.978727Z","steps":["trace[47374887] 'read index received'  (duration: 122.595731ms)","trace[47374887] 'applied index is now lower than readState.Index'  (duration: 166.225µs)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:51.978919Z","caller":"traceutil/trace.go:171","msg":"trace[1206710823] transaction","detail":"{read_only:false; response_revision:26389; number_of_response:1; }","duration":"126.842356ms","start":"2026-04-02T17:38:51.852067Z","end":"2026-04-02T17:38:51.978909Z","steps":["trace[1206710823] 'process raft request'  (duration: 126.459585ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:51.979082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.088195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:51.979175Z","caller":"traceutil/trace.go:171","msg":"trace[836591233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26389; }","duration":"123.215598ms","start":"2026-04-02T17:38:51.855937Z","end":"2026-04-02T17:38:51.979153Z","steps":["trace[836591233] 'agreement among raft nodes before linearized reading'  (duration: 123.053943ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.05683Z","caller":"traceutil/trace.go:171","msg":"trace[586075273] transaction","detail":"{read_only:false; response_revision:26390; number_of_response:1; }","duration":"121.914603ms","start":"2026-04-02T17:38:51.93489Z","end":"2026-04-02T17:38:52.056805Z","steps":["trace[586075273] 'process raft request'  (duration: 121.654786ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.14964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:52.057438Z","caller":"traceutil/trace.go:171","msg":"trace[1018152519] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26391; }","duration":"122.289854ms","start":"2026-04-02T17:38:51.935124Z","end":"2026-04-02T17:38:52.057414Z","steps":["trace[1018152519] 'agreement among raft nodes before linearized reading'  (duration: 122.085118ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.614846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:52.057512Z","caller":"traceutil/trace.go:171","msg":"trace[649194667] transaction","detail":"{read_only:false; response_revision:26391; number_of_response:1; }","duration":"122.396797ms","start":"2026-04-02T17:38:51.935102Z","end":"2026-04-02T17:38:52.057499Z","steps":["trace[649194667] 'process raft request'  (duration: 121.654206ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.902309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"warn","ts":"2026-04-02T17:38:52.057534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.901644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:52.057505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.372086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:52.057633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.474088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:52.057648Z","caller":"traceutil/trace.go:171","msg":"trace[1800385877] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26391; }","duration":"122.060187ms","start":"2026-04-02T17:38:51.935567Z","end":"2026-04-02T17:38:52.057627Z","steps":["trace[1800385877] 'agreement among raft nodes before linearized reading'  (duration: 121.828791ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.757249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:52.057707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.354195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:52.057544Z","caller":"traceutil/trace.go:171","msg":"trace[1503886339] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26391; }","duration":"121.751039ms","start":"2026-04-02T17:38:51.935764Z","end":"2026-04-02T17:38:52.057515Z","steps":["trace[1503886339] 'agreement among raft nodes before linearized reading'  (duration: 121.588635ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.057735Z","caller":"traceutil/trace.go:171","msg":"trace[183507925] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26391; }","duration":"119.059697ms","start":"2026-04-02T17:38:51.938665Z","end":"2026-04-02T17:38:52.057724Z","steps":["trace[183507925] 'agreement among raft nodes before linearized reading'  (duration: 118.749159ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.715845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-04-02T17:38:52.057741Z","caller":"traceutil/trace.go:171","msg":"trace[336366051] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26391; }","duration":"122.450958ms","start":"2026-04-02T17:38:51.935282Z","end":"2026-04-02T17:38:52.057733Z","steps":["trace[336366051] 'agreement among raft nodes before linearized reading'  (duration: 122.405947ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.057769Z","caller":"traceutil/trace.go:171","msg":"trace[559482580] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26391; }","duration":"119.810097ms","start":"2026-04-02T17:38:51.93795Z","end":"2026-04-02T17:38:52.05776Z","steps":["trace[559482580] 'agreement among raft nodes before linearized reading'  (duration: 119.697654ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.057685Z","caller":"traceutil/trace.go:171","msg":"trace[214225193] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26391; }","duration":"122.54571ms","start":"2026-04-02T17:38:51.935109Z","end":"2026-04-02T17:38:52.057655Z","steps":["trace[214225193] 'agreement among raft nodes before linearized reading'  (duration: 122.331584ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.721668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:52.057846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.201601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:52.057867Z","caller":"traceutil/trace.go:171","msg":"trace[775453422] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26391; }","duration":"122.012296ms","start":"2026-04-02T17:38:51.935846Z","end":"2026-04-02T17:38:52.057858Z","steps":["trace[775453422] 'agreement among raft nodes before linearized reading'  (duration: 121.728439ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.057667Z","caller":"traceutil/trace.go:171","msg":"trace[2019857259] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26391; }","duration":"122.506169ms","start":"2026-04-02T17:38:51.935151Z","end":"2026-04-02T17:38:52.057657Z","steps":["trace[2019857259] 'agreement among raft nodes before linearized reading'  (duration: 122.456988ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.057883Z","caller":"traceutil/trace.go:171","msg":"trace[343902878] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26391; }","duration":"122.252373ms","start":"2026-04-02T17:38:51.935622Z","end":"2026-04-02T17:38:52.057874Z","steps":["trace[343902878] 'agreement among raft nodes before linearized reading'  (duration: 122.199511ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.05761Z","caller":"traceutil/trace.go:171","msg":"trace[514814611] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26391; }","duration":"120.017242ms","start":"2026-04-02T17:38:51.937573Z","end":"2026-04-02T17:38:52.05759Z","steps":["trace[514814611] 'agreement among raft nodes before linearized reading'  (duration: 119.728025ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.220371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-04-02T17:38:52.057997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.239852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-04-02T17:38:52.058019Z","caller":"traceutil/trace.go:171","msg":"trace[506720035] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26391; }","duration":"122.276544ms","start":"2026-04-02T17:38:51.935737Z","end":"2026-04-02T17:38:52.058013Z","steps":["trace[506720035] 'agreement among raft nodes before linearized reading'  (duration: 122.186121ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.057381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.253413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:52.058021Z","caller":"traceutil/trace.go:171","msg":"trace[179587149] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26391; }","duration":"122.384996ms","start":"2026-04-02T17:38:51.935625Z","end":"2026-04-02T17:38:52.05801Z","steps":["trace[179587149] 'agreement among raft nodes before linearized reading'  (duration: 122.317845ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.058088Z","caller":"traceutil/trace.go:171","msg":"trace[1237507048] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26391; }","duration":"123.010733ms","start":"2026-04-02T17:38:51.935057Z","end":"2026-04-02T17:38:52.058068Z","steps":["trace[1237507048] 'agreement among raft nodes before linearized reading'  (duration: 122.201782ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:52.058202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.481248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-04-02T17:38:52.058254Z","caller":"traceutil/trace.go:171","msg":"trace[793889252] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26391; }","duration":"122.593682ms","start":"2026-04-02T17:38:51.935649Z","end":"2026-04-02T17:38:52.058242Z","steps":["trace[793889252] 'agreement among raft nodes before linearized reading'  (duration: 121.538764ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.411728Z","caller":"traceutil/trace.go:171","msg":"trace[1958252132] transaction","detail":"{read_only:false; response_revision:26394; number_of_response:1; }","duration":"272.230388ms","start":"2026-04-02T17:38:52.139463Z","end":"2026-04-02T17:38:52.411693Z","steps":["trace[1958252132] 'process raft request'  (duration: 271.92987ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:52.412077Z","caller":"traceutil/trace.go:171","msg":"trace[1157178115] transaction","detail":"{read_only:false; response_revision:26395; number_of_response:1; }","duration":"270.695147ms","start":"2026-04-02T17:38:52.141334Z","end":"2026-04-02T17:38:52.412029Z","steps":["trace[1157178115] 'process raft request'  (duration: 270.278386ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:53.050913Z","caller":"traceutil/trace.go:171","msg":"trace[1418355044] linearizableReadLoop","detail":"{readStateIndex:27261; appliedIndex:27260; }","duration":"111.247956ms","start":"2026-04-02T17:38:52.939635Z","end":"2026-04-02T17:38:53.050883Z","steps":["trace[1418355044] 'read index received'  (duration: 101.968167ms)","trace[1418355044] 'applied index is now lower than readState.Index'  (duration: 9.278279ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:38:53.050991Z","caller":"traceutil/trace.go:171","msg":"trace[892470610] transaction","detail":"{read_only:false; response_revision:26399; number_of_response:1; }","duration":"129.167838ms","start":"2026-04-02T17:38:52.921787Z","end":"2026-04-02T17:38:53.050955Z","steps":["trace[892470610] 'process raft request'  (duration: 119.747895ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-02T17:38:53.051238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.592655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"}
{"level":"warn","ts":"2026-04-02T17:38:53.051215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.799619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-04-02T17:38:53.051277Z","caller":"traceutil/trace.go:171","msg":"trace[922549900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26399; }","duration":"111.699838ms","start":"2026-04-02T17:38:52.939565Z","end":"2026-04-02T17:38:53.051265Z","steps":["trace[922549900] 'agreement among raft nodes before linearized reading'  (duration: 111.482402ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:53.051318Z","caller":"traceutil/trace.go:171","msg":"trace[1698683159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26399; }","duration":"105.944334ms","start":"2026-04-02T17:38:52.94535Z","end":"2026-04-02T17:38:53.051295Z","steps":["trace[1698683159] 'agreement among raft nodes before linearized reading'  (duration: 105.762909ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:55.635028Z","caller":"traceutil/trace.go:171","msg":"trace[1262698645] transaction","detail":"{read_only:false; response_revision:26416; number_of_response:1; }","duration":"112.155541ms","start":"2026-04-02T17:38:55.522844Z","end":"2026-04-02T17:38:55.635Z","steps":["trace[1262698645] 'process raft request'  (duration: 111.939665ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:38:55.736288Z","caller":"traceutil/trace.go:171","msg":"trace[1054256869] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"182.605512ms","start":"2026-04-02T17:38:55.553635Z","end":"2026-04-02T17:38:55.736241Z","steps":["trace[1054256869] 'process raft request'  (duration: 155.849513ms)","trace[1054256869] 'compare'  (duration: 26.497552ms)"],"step_count":2}
{"level":"info","ts":"2026-04-02T17:40:43.141156Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006e7a.wal"}
{"level":"info","ts":"2026-04-02T17:41:40.478962Z","caller":"traceutil/trace.go:171","msg":"trace[459511995] linearizableReadLoop","detail":"{readStateIndex:28902; appliedIndex:28901; }","duration":"117.71604ms","start":"2026-04-02T17:41:40.361214Z","end":"2026-04-02T17:41:40.47893Z","steps":["trace[459511995] 'read index received'  (duration: 117.406482ms)","trace[459511995] 'applied index is now lower than readState.Index'  (duration: 308.808µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-02T17:41:40.479252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.007929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"}
{"level":"info","ts":"2026-04-02T17:41:40.479296Z","caller":"traceutil/trace.go:171","msg":"trace[1373381247] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:27999; }","duration":"118.147513ms","start":"2026-04-02T17:41:40.361136Z","end":"2026-04-02T17:41:40.479284Z","steps":["trace[1373381247] 'agreement among raft nodes before linearized reading'  (duration: 117.923536ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:41:40.47946Z","caller":"traceutil/trace.go:171","msg":"trace[1694740309] transaction","detail":"{read_only:false; response_revision:27999; number_of_response:1; }","duration":"159.472571ms","start":"2026-04-02T17:41:40.319937Z","end":"2026-04-02T17:41:40.47941Z","steps":["trace[1694740309] 'process raft request'  (duration: 158.759411ms)"],"step_count":1}
{"level":"info","ts":"2026-04-02T17:43:07.806945Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26072}
{"level":"info","ts":"2026-04-02T17:43:07.958998Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26072,"took":"146.276047ms","hash":914569534,"current-db-size-bytes":59891712,"current-db-size":"60 MB","current-db-size-in-use-bytes":36544512,"current-db-size-in-use":"36 MB"}
{"level":"info","ts":"2026-04-02T17:43:07.959075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":914569534,"revision":26072,"compact-revision":23283}
{"level":"info","ts":"2026-04-02T17:43:48.002219Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-02T17:43:48.007814Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003}
{"level":"info","ts":"2026-04-02T17:43:48.00794Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}
{"level":"info","ts":"2026-04-02T17:48:07.813432Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28771}
{"level":"info","ts":"2026-04-02T17:48:07.96195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28771,"took":"142.676741ms","hash":941669634,"current-db-size-bytes":59891712,"current-db-size":"60 MB","current-db-size-in-use-bytes":31256576,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-04-02T17:48:07.96204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":941669634,"revision":28771,"compact-revision":26072}
{"level":"info","ts":"2026-04-02T17:53:07.820197Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30997}
{"level":"info","ts":"2026-04-02T17:53:07.941622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30997,"took":"115.863645ms","hash":2706509411,"current-db-size-bytes":59891712,"current-db-size":"60 MB","current-db-size-in-use-bytes":32104448,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-04-02T17:53:07.941703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2706509411,"revision":30997,"compact-revision":28771}
{"level":"info","ts":"2026-04-02T17:58:07.827992Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33267}
{"level":"info","ts":"2026-04-02T17:58:07.98865Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33267,"took":"155.060926ms","hash":3947060144,"current-db-size-bytes":60932096,"current-db-size":"61 MB","current-db-size-in-use-bytes":31784960,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-04-02T17:58:07.988731Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3947060144,"revision":33267,"compact-revision":30997}
{"level":"info","ts":"2026-04-02T18:03:07.83799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35558}
{"level":"info","ts":"2026-04-02T18:03:07.992145Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35558,"took":"149.495767ms","hash":1088498180,"current-db-size-bytes":61820928,"current-db-size":"62 MB","current-db-size-in-use-bytes":31399936,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-04-02T18:03:07.992268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1088498180,"revision":35558,"compact-revision":33267}
{"level":"info","ts":"2026-04-02T18:05:05.157578Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-04-02T18:05:05.162013Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004}
{"level":"info","ts":"2026-04-02T18:05:05.162114Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}
{"level":"info","ts":"2026-04-02T18:08:07.847757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37845}
{"level":"info","ts":"2026-04-02T18:08:07.982414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37845,"took":"129.431443ms","hash":458738900,"current-db-size-bytes":61820928,"current-db-size":"62 MB","current-db-size-in-use-bytes":31227904,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-04-02T18:08:07.982479Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":458738900,"revision":37845,"compact-revision":35558}