{"level":"warn","ts":"2026-05-18T00:40:15.581318Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-18T00:40:15.581436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-05-18T00:40:15.581509Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-18T00:40:15.581523Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]}
{"level":"info","ts":"2026-05-18T00:40:15.581542Z","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-05-18T00:40:15.58228Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]}
{"level":"info","ts":"2026-05-18T00:40:15.582367Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-05-18T00:40:15.586176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.555428ms"}
{"level":"info","ts":"2026-05-18T00:40:15.600256Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"}
{"level":"info","ts":"2026-05-18T00:40:15.600354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"}
{"level":"info","ts":"2026-05-18T00:40:15.60038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"}
{"level":"info","ts":"2026-05-18T00:40:15.600464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-05-18T00:40:15.600484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"}
{"level":"info","ts":"2026-05-18T00:40:15.600528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"}
{"level":"warn","ts":"2026-05-18T00:40:15.606698Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-05-18T00:40:15.608094Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-05-18T00:40:15.609272Z","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-05-18T00:40:15.611378Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-05-18T00:40:15.611595Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-05-18T00:40:15.611874Z","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-05-18T00:40:15.612032Z","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-05-18T00:40:15.612069Z","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-05-18T00:40:15.613553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"}
{"level":"info","ts":"2026-05-18T00:40:15.613823Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]}
{"level":"info","ts":"2026-05-18T00:40:15.616326Z","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-05-18T00:40:15.616618Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"}
{"level":"info","ts":"2026-05-18T00:40:15.616676Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"}
{"level":"info","ts":"2026-05-18T00:40:15.616712Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-05-18T00:40:15.616767Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-05-18T00:40:16.201176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"}
{"level":"info","ts":"2026-05-18T00:40:16.201251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"}
{"level":"info","ts":"2026-05-18T00:40:16.201292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"}
{"level":"info","ts":"2026-05-18T00:40:16.201313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"}
{"level":"info","ts":"2026-05-18T00:40:16.201324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"}
{"level":"info","ts":"2026-05-18T00:40:16.201342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"}
{"level":"info","ts":"2026-05-18T00:40:16.201356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"}
{"level":"info","ts":"2026-05-18T00:40:16.202255Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T00:40:16.20309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-18T00:40:16.203089Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"}
{"level":"info","ts":"2026-05-18T00:40:16.203161Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-18T00:40:16.203473Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-05-18T00:40:16.203521Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-05-18T00:40:16.203511Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T00:40:16.203656Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T00:40:16.203696Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T00:40:16.205659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"}
{"level":"info","ts":"2026-05-18T00:40:16.207172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-05-18T00:40:30.880885Z","caller":"traceutil/trace.go:171","msg":"trace[1437491185] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"130.58452ms","start":"2026-05-18T00:40:30.750268Z","end":"2026-05-18T00:40:30.880852Z","steps":["trace[1437491185] 'process raft request'  (duration: 130.396037ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:31.497708Z","caller":"traceutil/trace.go:171","msg":"trace[1997978277] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"274.037612ms","start":"2026-05-18T00:40:31.223646Z","end":"2026-05-18T00:40:31.497684Z","steps":["trace[1997978277] 'process raft request'  (duration: 273.90257ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:31.803656Z","caller":"traceutil/trace.go:171","msg":"trace[1812515359] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"239.706723ms","start":"2026-05-18T00:40:31.563919Z","end":"2026-05-18T00:40:31.803626Z","steps":["trace[1812515359] 'read index received'  (duration: 239.494749ms)","trace[1812515359] 'applied index is now lower than readState.Index'  (duration: 210.904µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:40:31.803734Z","caller":"traceutil/trace.go:171","msg":"trace[1203699124] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"257.113021ms","start":"2026-05-18T00:40:31.546573Z","end":"2026-05-18T00:40:31.803686Z","steps":["trace[1203699124] 'process raft request'  (duration: 256.841456ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:31.803848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.879196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:31.803917Z","caller":"traceutil/trace.go:171","msg":"trace[645937806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"240.045989ms","start":"2026-05-18T00:40:31.563858Z","end":"2026-05-18T00:40:31.803904Z","steps":["trace[645937806] 'agreement among raft nodes before linearized reading'  (duration: 239.893506ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:50.598028Z","caller":"traceutil/trace.go:171","msg":"trace[832143899] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"176.532174ms","start":"2026-05-18T00:40:50.42143Z","end":"2026-05-18T00:40:50.597962Z","steps":["trace[832143899] 'process raft request'  (duration: 174.209859ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:50.600738Z","caller":"traceutil/trace.go:171","msg":"trace[1425732773] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"101.76003ms","start":"2026-05-18T00:40:50.498947Z","end":"2026-05-18T00:40:50.600707Z","steps":["trace[1425732773] 'process raft request'  (duration: 101.554806ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.261857Z","caller":"traceutil/trace.go:171","msg":"trace[316828496] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"108.126385ms","start":"2026-05-18T00:40:56.153695Z","end":"2026-05-18T00:40:56.261822Z","steps":["trace[316828496] 'process raft request'  (duration: 107.329359ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.458408Z","caller":"traceutil/trace.go:171","msg":"trace[563768567] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"115.764215ms","start":"2026-05-18T00:40:56.342615Z","end":"2026-05-18T00:40:56.458379Z","steps":["trace[563768567] 'read index received'  (duration: 95.134412ms)","trace[563768567] 'applied index is now lower than readState.Index'  (duration: 20.628353ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:40:56.458597Z","caller":"traceutil/trace.go:171","msg":"trace[1445454551] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"133.852068ms","start":"2026-05-18T00:40:56.324711Z","end":"2026-05-18T00:40:56.458563Z","steps":["trace[1445454551] 'process raft request'  (duration: 113.142313ms)","trace[1445454551] 'compare'  (duration: 20.331847ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T00:40:56.458782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.130752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T00:40:56.458819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.03167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.458889Z","caller":"traceutil/trace.go:171","msg":"trace[1812568539] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"116.304056ms","start":"2026-05-18T00:40:56.342562Z","end":"2026-05-18T00:40:56.458866Z","steps":["trace[1812568539] 'agreement among raft nodes before linearized reading'  (duration: 116.076821ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.458932Z","caller":"traceutil/trace.go:171","msg":"trace[1041356781] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"116.216954ms","start":"2026-05-18T00:40:56.342691Z","end":"2026-05-18T00:40:56.458908Z","steps":["trace[1041356781] 'agreement among raft nodes before linearized reading'  (duration: 116.024439ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.950732Z","caller":"traceutil/trace.go:171","msg":"trace[1050222995] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"378.995264ms","start":"2026-05-18T00:40:56.571697Z","end":"2026-05-18T00:40:56.950692Z","steps":["trace[1050222995] 'process raft request'  (duration: 378.720549ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.951127Z","caller":"traceutil/trace.go:171","msg":"trace[1602635860] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:609; }","duration":"273.231965ms","start":"2026-05-18T00:40:56.677856Z","end":"2026-05-18T00:40:56.951088Z","steps":["trace[1602635860] 'read index received'  (duration: 273.211804ms)","trace[1602635860] 'applied index is now lower than readState.Index'  (duration: 17.011µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T00:40:56.951337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.43707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.951448Z","caller":"traceutil/trace.go:171","msg":"trace[745843853] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:589; }","duration":"273.575912ms","start":"2026-05-18T00:40:56.677847Z","end":"2026-05-18T00:40:56.951423Z","steps":["trace[745843853] 'agreement among raft nodes before linearized reading'  (duration: 273.327718ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.951477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:40:56.571668Z","time spent":"379.177918ms","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-z4z5z\" mod_revision:469 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-z4z5z\" value_size:17081 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-z4z5z\" > >"}
{"level":"warn","ts":"2026-05-18T00:40:56.966795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.819231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T00:40:56.966853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.055164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T00:40:56.966846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.266126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.966923Z","caller":"traceutil/trace.go:171","msg":"trace[1768628625] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:590; }","duration":"271.195216ms","start":"2026-05-18T00:40:56.695707Z","end":"2026-05-18T00:40:56.966902Z","steps":["trace[1768628625] 'agreement among raft nodes before linearized reading'  (duration: 271.023283ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.966919Z","caller":"traceutil/trace.go:171","msg":"trace[965855266] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:590; }","duration":"288.983214ms","start":"2026-05-18T00:40:56.677908Z","end":"2026-05-18T00:40:56.966892Z","steps":["trace[965855266] 'agreement among raft nodes before linearized reading'  (duration: 288.76953ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.966912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.583166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.966959Z","caller":"traceutil/trace.go:171","msg":"trace[1266441115] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:590; }","duration":"252.454849ms","start":"2026-05-18T00:40:56.714469Z","end":"2026-05-18T00:40:56.966924Z","steps":["trace[1266441115] 'agreement among raft nodes before linearized reading'  (duration: 252.233915ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.966932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.224396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.966983Z","caller":"traceutil/trace.go:171","msg":"trace[959425242] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:590; }","duration":"288.681168ms","start":"2026-05-18T00:40:56.678283Z","end":"2026-05-18T00:40:56.966964Z","steps":["trace[959425242] 'agreement among raft nodes before linearized reading'  (duration: 288.545135ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.967037Z","caller":"traceutil/trace.go:171","msg":"trace[1235673193] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:590; }","duration":"271.357919ms","start":"2026-05-18T00:40:56.69565Z","end":"2026-05-18T00:40:56.967008Z","steps":["trace[1235673193] 'agreement among raft nodes before linearized reading'  (duration: 271.138805ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.967024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.231237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T00:40:56.967049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.069142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T00:40:56.967074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.033741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.967121Z","caller":"traceutil/trace.go:171","msg":"trace[296045004] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:590; }","duration":"271.38957ms","start":"2026-05-18T00:40:56.695707Z","end":"2026-05-18T00:40:56.967096Z","steps":["trace[296045004] 'agreement among raft nodes before linearized reading'  (duration: 271.169355ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.967161Z","caller":"traceutil/trace.go:171","msg":"trace[240036532] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:590; }","duration":"253.169493ms","start":"2026-05-18T00:40:56.713965Z","end":"2026-05-18T00:40:56.967135Z","steps":["trace[240036532] 'agreement among raft nodes before linearized reading'  (duration: 253.01014ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.967157Z","caller":"traceutil/trace.go:171","msg":"trace[280965831] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:590; }","duration":"253.226724ms","start":"2026-05-18T00:40:56.7139Z","end":"2026-05-18T00:40:56.967127Z","steps":["trace[280965831] 'agreement among raft nodes before linearized reading'  (duration: 253.00472ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.967179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.732137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.967225Z","caller":"traceutil/trace.go:171","msg":"trace[2084920470] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:590; }","duration":"202.788738ms","start":"2026-05-18T00:40:56.76442Z","end":"2026-05-18T00:40:56.967209Z","steps":["trace[2084920470] 'agreement among raft nodes before linearized reading'  (duration: 202.696236ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.967289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.797248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T00:40:56.96732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.373053ms","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:5"}
{"level":"info","ts":"2026-05-18T00:40:56.967329Z","caller":"traceutil/trace.go:171","msg":"trace[1933861582] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:590; }","duration":"203.875889ms","start":"2026-05-18T00:40:56.763441Z","end":"2026-05-18T00:40:56.967317Z","steps":["trace[1933861582] 'agreement among raft nodes before linearized reading'  (duration: 203.821618ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.967318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.470604ms","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-05-18T00:40:56.967355Z","caller":"traceutil/trace.go:171","msg":"trace[99477198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:590; }","duration":"220.428373ms","start":"2026-05-18T00:40:56.746912Z","end":"2026-05-18T00:40:56.967341Z","steps":["trace[99477198] 'agreement among raft nodes before linearized reading'  (duration: 220.383192ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:40:56.967037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.517682ms","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:5"}
{"level":"warn","ts":"2026-05-18T00:40:56.967241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.994665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T00:40:56.967425Z","caller":"traceutil/trace.go:171","msg":"trace[1821386294] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:590; }","duration":"203.972211ms","start":"2026-05-18T00:40:56.763441Z","end":"2026-05-18T00:40:56.967414Z","steps":["trace[1821386294] 'agreement among raft nodes before linearized reading'  (duration: 203.495162ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.967448Z","caller":"traceutil/trace.go:171","msg":"trace[1259837004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"266.685708ms","start":"2026-05-18T00:40:56.700725Z","end":"2026-05-18T00:40:56.967411Z","steps":["trace[1259837004] 'agreement among raft nodes before linearized reading'  (duration: 266.236809ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:40:56.967464Z","caller":"traceutil/trace.go:171","msg":"trace[955461959] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:590; }","duration":"220.211839ms","start":"2026-05-18T00:40:56.747234Z","end":"2026-05-18T00:40:56.967446Z","steps":["trace[955461959] 'agreement among raft nodes before linearized reading'  (duration: 219.976745ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:41:13.273059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.10008ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215427684 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:743 > 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:16"}
{"level":"info","ts":"2026-05-18T00:41:13.273495Z","caller":"traceutil/trace.go:171","msg":"trace[1756434464] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"146.15496ms","start":"2026-05-18T00:41:13.127287Z","end":"2026-05-18T00:41:13.273442Z","steps":["trace[1756434464] 'process raft request'  (duration: 26.362776ms)","trace[1756434464] 'compare'  (duration: 118.927787ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:42:24.213564Z","caller":"traceutil/trace.go:171","msg":"trace[1632524476] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"144.627561ms","start":"2026-05-18T00:42:24.068902Z","end":"2026-05-18T00:42:24.21353Z","steps":["trace[1632524476] 'read index received'  (duration: 144.367477ms)","trace[1632524476] 'applied index is now lower than readState.Index'  (duration: 259.244µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:42:24.213678Z","caller":"traceutil/trace.go:171","msg":"trace[1850949998] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"211.169165ms","start":"2026-05-18T00:42:24.002462Z","end":"2026-05-18T00:42:24.213631Z","steps":["trace[1850949998] 'process raft request'  (duration: 210.88537ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:42:24.213858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.824147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"}
{"level":"info","ts":"2026-05-18T00:42:24.213918Z","caller":"traceutil/trace.go:171","msg":"trace[682578118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1804; }","duration":"143.95358ms","start":"2026-05-18T00:42:24.06994Z","end":"2026-05-18T00:42:24.213894Z","steps":["trace[682578118] 'agreement among raft nodes before linearized reading'  (duration: 143.769766ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:42:24.214025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.05512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7969c203-9297-407d-ad01-933e8e4e4aab\" ","response":"range_response_count:1 size:3626"}
{"level":"info","ts":"2026-05-18T00:42:24.214163Z","caller":"traceutil/trace.go:171","msg":"trace[1347637658] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7969c203-9297-407d-ad01-933e8e4e4aab; range_end:; response_count:1; response_revision:1804; }","duration":"145.261633ms","start":"2026-05-18T00:42:24.068868Z","end":"2026-05-18T00:42:24.214129Z","steps":["trace[1347637658] 'agreement among raft nodes before linearized reading'  (duration: 144.815045ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:42:24.345524Z","caller":"traceutil/trace.go:171","msg":"trace[1254128629] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"126.069209ms","start":"2026-05-18T00:42:24.219427Z","end":"2026-05-18T00:42:24.345496Z","steps":["trace[1254128629] 'process raft request'  (duration: 125.56587ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:42:24.420219Z","caller":"traceutil/trace.go:171","msg":"trace[1432048727] linearizableReadLoop","detail":"{readStateIndex:1873; appliedIndex:1871; }","duration":"128.35094ms","start":"2026-05-18T00:42:24.291841Z","end":"2026-05-18T00:42:24.420192Z","steps":["trace[1432048727] 'read index received'  (duration: 53.181833ms)","trace[1432048727] 'applied index is now lower than readState.Index'  (duration: 75.167967ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:42:24.420339Z","caller":"traceutil/trace.go:171","msg":"trace[916481360] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"197.373326ms","start":"2026-05-18T00:42:24.222937Z","end":"2026-05-18T00:42:24.420311Z","steps":["trace[916481360] 'process raft request'  (duration: 197.109312ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:42:24.420586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.646536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-05-18T00:42:24.420651Z","caller":"traceutil/trace.go:171","msg":"trace[168081002] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1806; }","duration":"128.867169ms","start":"2026-05-18T00:42:24.29177Z","end":"2026-05-18T00:42:24.420637Z","steps":["trace[168081002] 'agreement among raft nodes before linearized reading'  (duration: 128.531103ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:42:24.648243Z","caller":"traceutil/trace.go:171","msg":"trace[2077991538] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"168.546281ms","start":"2026-05-18T00:42:24.479673Z","end":"2026-05-18T00:42:24.648219Z","steps":["trace[2077991538] 'process raft request'  (duration: 163.845407ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:42:24.648532Z","caller":"traceutil/trace.go:171","msg":"trace[785911741] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"167.95864ms","start":"2026-05-18T00:42:24.480567Z","end":"2026-05-18T00:42:24.648525Z","steps":["trace[785911741] 'process raft request'  (duration: 167.619553ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:42:24.648793Z","caller":"traceutil/trace.go:171","msg":"trace[534425439] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"168.039291ms","start":"2026-05-18T00:42:24.480737Z","end":"2026-05-18T00:42:24.648776Z","steps":["trace[534425439] 'process raft request'  (duration: 167.720845ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:43:55.050123Z","caller":"traceutil/trace.go:171","msg":"trace[1836259830] linearizableReadLoop","detail":"{readStateIndex:2619; appliedIndex:2618; }","duration":"190.110925ms","start":"2026-05-18T00:43:54.859969Z","end":"2026-05-18T00:43:55.05008Z","steps":["trace[1836259830] 'read index received'  (duration: 182.641597ms)","trace[1836259830] 'applied index is now lower than readState.Index'  (duration: 7.464938ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T00:43:55.050424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.429571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-05-18T00:43:55.050503Z","caller":"traceutil/trace.go:171","msg":"trace[144905267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2525; }","duration":"190.547633ms","start":"2026-05-18T00:43:54.859918Z","end":"2026-05-18T00:43:55.050466Z","steps":["trace[144905267] 'agreement among raft nodes before linearized reading'  (duration: 190.349049ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:44:28.346978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.028317ms","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-05-18T00:44:28.347097Z","caller":"traceutil/trace.go:171","msg":"trace[1950744931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2728; }","duration":"112.20048ms","start":"2026-05-18T00:44:28.234872Z","end":"2026-05-18T00:44:28.347073Z","steps":["trace[1950744931] 'range keys from in-memory index tree'  (duration: 111.805133ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:44:28.492024Z","caller":"traceutil/trace.go:171","msg":"trace[895323199] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"141.218235ms","start":"2026-05-18T00:44:28.350773Z","end":"2026-05-18T00:44:28.491991Z","steps":["trace[895323199] 'process raft request'  (duration: 141.054672ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:44:28.600006Z","caller":"traceutil/trace.go:171","msg":"trace[826425701] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"248.104608ms","start":"2026-05-18T00:44:28.35186Z","end":"2026-05-18T00:44:28.599965Z","steps":["trace[826425701] 'process raft request'  (duration: 232.372954ms)","trace[826425701] 'compare'  (duration: 15.587761ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T00:48:18.501311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.045743ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215437085 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/cilium.io/ciliumendpoints/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumendpoints/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" value_size:3517 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-05-18T00:48:18.501609Z","caller":"traceutil/trace.go:171","msg":"trace[454193435] linearizableReadLoop","detail":"{readStateIndex:5035; appliedIndex:5032; }","duration":"146.899848ms","start":"2026-05-18T00:48:18.354687Z","end":"2026-05-18T00:48:18.501587Z","steps":["trace[454193435] 'read index received'  (duration: 46.471165ms)","trace[454193435] 'applied index is now lower than readState.Index'  (duration: 100.427203ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T00:48:18.501805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.122783ms","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-05-18T00:48:18.501895Z","caller":"traceutil/trace.go:171","msg":"trace[522079746] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4856; }","duration":"147.212816ms","start":"2026-05-18T00:48:18.354663Z","end":"2026-05-18T00:48:18.501876Z","steps":["trace[522079746] 'agreement among raft nodes before linearized reading'  (duration: 146.98441ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:48:18.501962Z","caller":"traceutil/trace.go:171","msg":"trace[1396685432] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"171.12343ms","start":"2026-05-18T00:48:18.330806Z","end":"2026-05-18T00:48:18.50193Z","steps":["trace[1396685432] 'process raft request'  (duration: 70.369799ms)","trace[1396685432] 'compare'  (duration: 99.870439ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:48:18.502187Z","caller":"traceutil/trace.go:171","msg":"trace[1527523899] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"169.274563ms","start":"2026-05-18T00:48:18.332898Z","end":"2026-05-18T00:48:18.502173Z","steps":["trace[1527523899] 'process raft request'  (duration: 168.548534ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:48:18.502388Z","caller":"traceutil/trace.go:171","msg":"trace[283182864] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"166.489612ms","start":"2026-05-18T00:48:18.335887Z","end":"2026-05-18T00:48:18.502377Z","steps":["trace[283182864] 'process raft request'  (duration: 165.64735ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:48:18.643793Z","caller":"traceutil/trace.go:171","msg":"trace[2025494006] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"129.375417ms","start":"2026-05-18T00:48:18.514382Z","end":"2026-05-18T00:48:18.643758Z","steps":["trace[2025494006] 'process raft request'  (duration: 129.136541ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:48:29.925409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.9383ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215437574 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.204.45.44\" mod_revision:4888 > success:<request_put:<key:\"/registry/masterleases/199.204.45.44\" value_size:66 lease:4487166569360661764 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.44\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-18T00:48:29.925533Z","caller":"traceutil/trace.go:171","msg":"trace[380953356] linearizableReadLoop","detail":"{readStateIndex:5278; appliedIndex:5277; }","duration":"105.197945ms","start":"2026-05-18T00:48:29.820318Z","end":"2026-05-18T00:48:29.925516Z","steps":["trace[380953356] 'read index received'  (duration: 52.261µs)","trace[380953356] 'applied index is now lower than readState.Index'  (duration: 105.144293ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:48:29.925623Z","caller":"traceutil/trace.go:171","msg":"trace[255880379] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"235.764163ms","start":"2026-05-18T00:48:29.689847Z","end":"2026-05-18T00:48:29.925611Z","steps":["trace[255880379] 'process raft request'  (duration: 37.538265ms)","trace[255880379] 'compare'  (duration: 197.770936ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T00:48:29.926015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.686448ms","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-05-18T00:48:29.9261Z","caller":"traceutil/trace.go:171","msg":"trace[1051990187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5091; }","duration":"105.818951ms","start":"2026-05-18T00:48:29.820262Z","end":"2026-05-18T00:48:29.926081Z","steps":["trace[1051990187] 'agreement among raft nodes before linearized reading'  (duration: 105.411781ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:48:29.926012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.725806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-05-18T00:48:29.92621Z","caller":"traceutil/trace.go:171","msg":"trace[1967296860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5091; }","duration":"101.962462ms","start":"2026-05-18T00:48:29.82422Z","end":"2026-05-18T00:48:29.926183Z","steps":["trace[1967296860] 'agreement among raft nodes before linearized reading'  (duration: 101.411258ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:48:46.561868Z","caller":"traceutil/trace.go:171","msg":"trace[163166786] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"315.162145ms","start":"2026-05-18T00:48:46.246678Z","end":"2026-05-18T00:48:46.56184Z","steps":["trace[163166786] 'process raft request'  (duration: 314.98729ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:48:46.562149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:48:46.246659Z","time spent":"315.373561ms","remote":"127.0.0.1:50268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3747,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b42dcd2-7f50-4c0c-8624-bf7f1dd91dc3\" mod_revision:5359 > success:<request_put:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b42dcd2-7f50-4c0c-8624-bf7f1dd91dc3\" value_size:3647 >> failure:<request_range:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b42dcd2-7f50-4c0c-8624-bf7f1dd91dc3\" > >"}
{"level":"info","ts":"2026-05-18T00:48:46.568198Z","caller":"traceutil/trace.go:171","msg":"trace[1670919323] linearizableReadLoop","detail":"{readStateIndex:5568; appliedIndex:5566; }","duration":"240.29981ms","start":"2026-05-18T00:48:46.327881Z","end":"2026-05-18T00:48:46.568181Z","steps":["trace[1670919323] 'read index received'  (duration: 233.896615ms)","trace[1670919323] 'applied index is now lower than readState.Index'  (duration: 6.400695ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:48:46.568279Z","caller":"traceutil/trace.go:171","msg":"trace[1975316170] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"286.843306ms","start":"2026-05-18T00:48:46.281427Z","end":"2026-05-18T00:48:46.56827Z","steps":["trace[1975316170] 'process raft request'  (duration: 286.656771ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:48:46.568371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.657772ms","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:7"}
{"level":"warn","ts":"2026-05-18T00:48:46.568485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.593977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"}
{"level":"info","ts":"2026-05-18T00:48:46.568503Z","caller":"traceutil/trace.go:171","msg":"trace[28114329] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5376; }","duration":"181.842117ms","start":"2026-05-18T00:48:46.386649Z","end":"2026-05-18T00:48:46.568491Z","steps":["trace[28114329] 'agreement among raft nodes before linearized reading'  (duration: 181.659812ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:48:46.568521Z","caller":"traceutil/trace.go:171","msg":"trace[1907994786] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5376; }","duration":"240.657049ms","start":"2026-05-18T00:48:46.327853Z","end":"2026-05-18T00:48:46.56851Z","steps":["trace[1907994786] 'agreement among raft nodes before linearized reading'  (duration: 240.535776ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:48:56.971371Z","caller":"traceutil/trace.go:171","msg":"trace[1126156901] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"106.281525ms","start":"2026-05-18T00:48:56.865058Z","end":"2026-05-18T00:48:56.97134Z","steps":["trace[1126156901] 'process raft request'  (duration: 63.618795ms)","trace[1126156901] 'compare'  (duration: 42.456056ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:50:16.419551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991}
{"level":"info","ts":"2026-05-18T00:50:16.487874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"66.923849ms","hash":2078609577,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":34230272,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-05-18T00:50:16.487962Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2078609577,"revision":2991,"compact-revision":-1}
{"level":"info","ts":"2026-05-18T00:54:33.991721Z","caller":"traceutil/trace.go:171","msg":"trace[1202062077] transaction","detail":"{read_only:false; response_revision:8041; number_of_response:1; }","duration":"187.63353ms","start":"2026-05-18T00:54:33.804062Z","end":"2026-05-18T00:54:33.991695Z","steps":["trace[1202062077] 'process raft request'  (duration: 187.461457ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:34.264823Z","caller":"traceutil/trace.go:171","msg":"trace[2129900924] transaction","detail":"{read_only:false; response_revision:8045; number_of_response:1; }","duration":"277.700798ms","start":"2026-05-18T00:54:33.987096Z","end":"2026-05-18T00:54:34.264797Z","steps":["trace[2129900924] 'process raft request'  (duration: 277.627636ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:34.264825Z","caller":"traceutil/trace.go:171","msg":"trace[470892038] transaction","detail":"{read_only:false; response_revision:8042; number_of_response:1; }","duration":"459.79098ms","start":"2026-05-18T00:54:33.805004Z","end":"2026-05-18T00:54:34.264795Z","steps":["trace[470892038] 'process raft request'  (duration: 459.493454ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:34.264871Z","caller":"traceutil/trace.go:171","msg":"trace[1698289015] transaction","detail":"{read_only:false; response_revision:8043; number_of_response:1; }","duration":"396.610168ms","start":"2026-05-18T00:54:33.868231Z","end":"2026-05-18T00:54:34.264841Z","steps":["trace[1698289015] 'process raft request'  (duration: 396.414414ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:34.264938Z","caller":"traceutil/trace.go:171","msg":"trace[702644592] transaction","detail":"{read_only:false; response_revision:8044; number_of_response:1; }","duration":"389.448418ms","start":"2026-05-18T00:54:33.875444Z","end":"2026-05-18T00:54:34.264893Z","steps":["trace[702644592] 'process raft request'  (duration: 389.246514ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:54:34.265051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:54:33.804985Z","time spent":"459.972174ms","remote":"127.0.0.1:50352","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:8032 > 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":"warn","ts":"2026-05-18T00:54:34.265104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:54:33.868206Z","time spent":"396.803422ms","remote":"127.0.0.1:50352","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:8033 > 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-05-18T00:54:34.26512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:54:33.875418Z","time spent":"389.610451ms","remote":"127.0.0.1:50352","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:8034 > 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":"info","ts":"2026-05-18T00:54:34.265263Z","caller":"traceutil/trace.go:171","msg":"trace[672329447] linearizableReadLoop","detail":"{readStateIndex:8324; appliedIndex:8319; }","duration":"185.118981ms","start":"2026-05-18T00:54:34.080127Z","end":"2026-05-18T00:54:34.265246Z","steps":["trace[672329447] 'read index received'  (duration: 184.358907ms)","trace[672329447] 'applied index is now lower than readState.Index'  (duration: 758.884µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:54:34.265349Z","caller":"traceutil/trace.go:171","msg":"trace[2118415010] transaction","detail":"{read_only:false; response_revision:8046; number_of_response:1; }","duration":"249.489828ms","start":"2026-05-18T00:54:34.015832Z","end":"2026-05-18T00:54:34.265322Z","steps":["trace[2118415010] 'process raft request'  (duration: 249.354435ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T00:54:34.265415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.279444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wmzjv\" ","response":"range_response_count:1 size:6520"}
{"level":"info","ts":"2026-05-18T00:54:34.265447Z","caller":"traceutil/trace.go:171","msg":"trace[648037123] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wmzjv; range_end:; response_count:1; response_revision:8046; }","duration":"185.368686ms","start":"2026-05-18T00:54:34.080071Z","end":"2026-05-18T00:54:34.265439Z","steps":["trace[648037123] 'agreement among raft nodes before linearized reading'  (duration: 185.257144ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:44.655731Z","caller":"traceutil/trace.go:171","msg":"trace[593511703] transaction","detail":"{read_only:false; response_revision:8099; number_of_response:1; }","duration":"102.306432ms","start":"2026-05-18T00:54:44.553402Z","end":"2026-05-18T00:54:44.655709Z","steps":["trace[593511703] 'process raft request'  (duration: 102.157749ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:46.797389Z","caller":"traceutil/trace.go:171","msg":"trace[2136298425] transaction","detail":"{read_only:false; response_revision:8108; number_of_response:1; }","duration":"133.191824ms","start":"2026-05-18T00:54:46.664168Z","end":"2026-05-18T00:54:46.79736Z","steps":["trace[2136298425] 'process raft request'  (duration: 133.021191ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:48.458263Z","caller":"traceutil/trace.go:171","msg":"trace[100014417] transaction","detail":"{read_only:false; response_revision:8115; number_of_response:1; }","duration":"123.967475ms","start":"2026-05-18T00:54:48.334267Z","end":"2026-05-18T00:54:48.458235Z","steps":["trace[100014417] 'process raft request'  (duration: 123.802432ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:54:48.459872Z","caller":"traceutil/trace.go:171","msg":"trace[120810852] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"105.422692ms","start":"2026-05-18T00:54:48.35442Z","end":"2026-05-18T00:54:48.459843Z","steps":["trace[120810852] 'process raft request'  (duration: 104.973504ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:55:14.779605Z","caller":"traceutil/trace.go:171","msg":"trace[554615888] transaction","detail":"{read_only:false; response_revision:8313; number_of_response:1; }","duration":"148.93979ms","start":"2026-05-18T00:55:14.630633Z","end":"2026-05-18T00:55:14.779573Z","steps":["trace[554615888] 'process raft request'  (duration: 90.273139ms)","trace[554615888] 'compare'  (duration: 58.468767ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:55:14.779583Z","caller":"traceutil/trace.go:171","msg":"trace[1056532638] linearizableReadLoop","detail":"{readStateIndex:8602; appliedIndex:8600; }","duration":"147.423481ms","start":"2026-05-18T00:55:14.632125Z","end":"2026-05-18T00:55:14.779548Z","steps":["trace[1056532638] 'read index received'  (duration: 42.60991ms)","trace[1056532638] 'applied index is now lower than readState.Index'  (duration: 104.812371ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T00:55:14.780056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.904941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228536"}
{"level":"info","ts":"2026-05-18T00:55:14.78014Z","caller":"traceutil/trace.go:171","msg":"trace[1464913944] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8313; }","duration":"148.024343ms","start":"2026-05-18T00:55:14.632096Z","end":"2026-05-18T00:55:14.780121Z","steps":["trace[1464913944] 'agreement among raft nodes before linearized reading'  (duration: 147.542054ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:55:16.425491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6115}
{"level":"info","ts":"2026-05-18T00:55:16.515485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6115,"took":"87.945362ms","hash":1920877276,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":22958080,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-05-18T00:55:16.515628Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1920877276,"revision":6115,"compact-revision":2991}
{"level":"warn","ts":"2026-05-18T00:55:25.670358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.994479ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215446529 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-xjwkm\" mod_revision:8282 > success:<request_delete_range:<key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-xjwkm\" > > failure:<request_range:<key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-xjwkm\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T00:55:25.670694Z","caller":"traceutil/trace.go:171","msg":"trace[536476316] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8413; }","duration":"128.739747ms","start":"2026-05-18T00:55:25.541826Z","end":"2026-05-18T00:55:25.670566Z","steps":["trace[536476316] 'compare'  (duration: 114.808416ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:55:25.670769Z","caller":"traceutil/trace.go:171","msg":"trace[1174594600] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"128.802738ms","start":"2026-05-18T00:55:25.541944Z","end":"2026-05-18T00:55:25.670747Z","steps":["trace[1174594600] 'process raft request'  (duration: 128.560934ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:55:26.636031Z","caller":"traceutil/trace.go:171","msg":"trace[297742884] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"278.581585ms","start":"2026-05-18T00:55:26.357417Z","end":"2026-05-18T00:55:26.635998Z","steps":["trace[297742884] 'process raft request'  (duration: 278.381041ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:55:26.722788Z","caller":"traceutil/trace.go:171","msg":"trace[1747906229] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"205.035722ms","start":"2026-05-18T00:55:26.517737Z","end":"2026-05-18T00:55:26.722773Z","steps":["trace[1747906229] 'process raft request'  (duration: 204.980641ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T00:55:26.723004Z","caller":"traceutil/trace.go:171","msg":"trace[578964056] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"236.070307ms","start":"2026-05-18T00:55:26.486806Z","end":"2026-05-18T00:55:26.722876Z","steps":["trace[578964056] 'process raft request'  (duration: 207.212145ms)","trace[578964056] 'compare'  (duration: 28.557566ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:56:16.972928Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002426.wal"}
{"level":"info","ts":"2026-05-18T00:56:33.50886Z","caller":"traceutil/trace.go:171","msg":"trace[1251812972] transaction","detail":"{read_only:false; response_revision:9054; number_of_response:1; }","duration":"195.695171ms","start":"2026-05-18T00:56:33.313134Z","end":"2026-05-18T00:56:33.508829Z","steps":["trace[1251812972] 'process raft request'  (duration: 184.061901ms)","trace[1251812972] 'compare'  (duration: 11.252323ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T00:57:47.274197Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-18T00:57:47.279415Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002}
{"level":"info","ts":"2026-05-18T00:57:47.279552Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002}
{"level":"info","ts":"2026-05-18T01:00:16.432546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8318}
{"level":"info","ts":"2026-05-18T01:00:16.507658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8318,"took":"72.292495ms","hash":3943176649,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":27078656,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-05-18T01:00:16.507739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3943176649,"revision":8318,"compact-revision":6115}
{"level":"info","ts":"2026-05-18T01:05:16.43984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11021}
{"level":"info","ts":"2026-05-18T01:05:16.522174Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11021,"took":"79.522405ms","hash":1676818680,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":28139520,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-05-18T01:05:16.522233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1676818680,"revision":11021,"compact-revision":8318}
{"level":"info","ts":"2026-05-18T01:05:54.814814Z","caller":"traceutil/trace.go:171","msg":"trace[149538876] transaction","detail":"{read_only:false; response_revision:13355; number_of_response:1; }","duration":"117.687285ms","start":"2026-05-18T01:05:54.697096Z","end":"2026-05-18T01:05:54.814783Z","steps":["trace[149538876] 'process raft request'  (duration: 117.280188ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:06:16.625884Z","caller":"traceutil/trace.go:171","msg":"trace[146752600] linearizableReadLoop","detail":"{readStateIndex:13929; appliedIndex:13928; }","duration":"105.542079ms","start":"2026-05-18T01:06:16.520322Z","end":"2026-05-18T01:06:16.625864Z","steps":["trace[146752600] 'read index received'  (duration: 105.424387ms)","trace[146752600] 'applied index is now lower than readState.Index'  (duration: 116.952µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:06:16.62602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.675893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T01:06:16.626047Z","caller":"traceutil/trace.go:171","msg":"trace[1083783883] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13474; }","duration":"105.736924ms","start":"2026-05-18T01:06:16.520303Z","end":"2026-05-18T01:06:16.62604Z","steps":["trace[1083783883] 'agreement among raft nodes before linearized reading'  (duration: 105.648402ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:06:16.626166Z","caller":"traceutil/trace.go:171","msg":"trace[1303448073] transaction","detail":"{read_only:false; response_revision:13474; number_of_response:1; }","duration":"107.679699ms","start":"2026-05-18T01:06:16.518457Z","end":"2026-05-18T01:06:16.626137Z","steps":["trace[1303448073] 'process raft request'  (duration: 107.270412ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:06:31.213979Z","caller":"traceutil/trace.go:171","msg":"trace[193991022] transaction","detail":"{read_only:false; response_revision:13563; number_of_response:1; }","duration":"197.693893ms","start":"2026-05-18T01:06:31.016254Z","end":"2026-05-18T01:06:31.213948Z","steps":["trace[193991022] 'process raft request'  (duration: 197.488079ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:06:31.414087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.171982ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215460892 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:13538 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-18T01:06:31.414264Z","caller":"traceutil/trace.go:171","msg":"trace[1707287812] linearizableReadLoop","detail":"{readStateIndex:14025; appliedIndex:14022; }","duration":"200.340982ms","start":"2026-05-18T01:06:31.21391Z","end":"2026-05-18T01:06:31.414251Z","steps":["trace[1707287812] 'read index received'  (duration: 47.948727ms)","trace[1707287812] 'applied index is now lower than readState.Index'  (duration: 152.391595ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:06:31.414315Z","caller":"traceutil/trace.go:171","msg":"trace[994723974] transaction","detail":"{read_only:false; response_revision:13565; number_of_response:1; }","duration":"222.96079ms","start":"2026-05-18T01:06:31.191328Z","end":"2026-05-18T01:06:31.414289Z","steps":["trace[994723974] 'process raft request'  (duration: 222.845748ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:06:31.414397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.372096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T01:06:31.414429Z","caller":"traceutil/trace.go:171","msg":"trace[575604007] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:13566; }","duration":"138.431057ms","start":"2026-05-18T01:06:31.275987Z","end":"2026-05-18T01:06:31.414418Z","steps":["trace[575604007] 'agreement among raft nodes before linearized reading'  (duration: 138.377626ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:06:31.41439Z","caller":"traceutil/trace.go:171","msg":"trace[2035956502] transaction","detail":"{read_only:false; response_revision:13566; number_of_response:1; }","duration":"222.694005ms","start":"2026-05-18T01:06:31.191646Z","end":"2026-05-18T01:06:31.41434Z","steps":["trace[2035956502] 'process raft request'  (duration: 222.571492ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:06:31.414645Z","caller":"traceutil/trace.go:171","msg":"trace[2080042837] transaction","detail":"{read_only:false; response_revision:13564; number_of_response:1; }","duration":"224.923386ms","start":"2026-05-18T01:06:31.189641Z","end":"2026-05-18T01:06:31.414564Z","steps":["trace[2080042837] 'process raft request'  (duration: 72.203364ms)","trace[2080042837] 'compare'  (duration: 152.07727ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:06:31.415236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.969602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T01:06:31.415377Z","caller":"traceutil/trace.go:171","msg":"trace[774242555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13566; }","duration":"235.112354ms","start":"2026-05-18T01:06:31.180212Z","end":"2026-05-18T01:06:31.415324Z","steps":["trace[774242555] 'agreement among raft nodes before linearized reading'  (duration: 234.230177ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:06:39.547583Z","caller":"traceutil/trace.go:171","msg":"trace[844757084] transaction","detail":"{read_only:false; response_revision:13719; number_of_response:1; }","duration":"104.149276ms","start":"2026-05-18T01:06:39.443409Z","end":"2026-05-18T01:06:39.547558Z","steps":["trace[844757084] 'process raft request'  (duration: 104.103705ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:06:39.547905Z","caller":"traceutil/trace.go:171","msg":"trace[10296068] transaction","detail":"{read_only:false; response_revision:13718; number_of_response:1; }","duration":"107.303225ms","start":"2026-05-18T01:06:39.440574Z","end":"2026-05-18T01:06:39.547878Z","steps":["trace[10296068] 'process raft request'  (duration: 106.800016ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.269865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.038281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T01:08:17.269948Z","caller":"traceutil/trace.go:171","msg":"trace[605736430] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14797; }","duration":"176.167763ms","start":"2026-05-18T01:08:17.093757Z","end":"2026-05-18T01:08:17.269925Z","steps":["trace[605736430] 'range keys from in-memory index tree'  (duration: 175.890798ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.269917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.131544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T01:08:17.270005Z","caller":"traceutil/trace.go:171","msg":"trace[808539827] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14797; }","duration":"175.246857ms","start":"2026-05-18T01:08:17.094738Z","end":"2026-05-18T01:08:17.269985Z","steps":["trace[808539827] 'range keys from in-memory index tree'  (duration: 175.088413ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.270075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.095654ms","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-05-18T01:08:17.270136Z","caller":"traceutil/trace.go:171","msg":"trace[785930148] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14797; }","duration":"175.211267ms","start":"2026-05-18T01:08:17.09491Z","end":"2026-05-18T01:08:17.270121Z","steps":["trace[785930148] 'range keys from in-memory index tree'  (duration: 174.897551ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.270137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.219655ms","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-05-18T01:08:17.270262Z","caller":"traceutil/trace.go:171","msg":"trace[956114420] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14797; }","duration":"176.378108ms","start":"2026-05-18T01:08:17.093858Z","end":"2026-05-18T01:08:17.270236Z","steps":["trace[956114420] 'range keys from in-memory index tree'  (duration: 176.155584ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.270094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.358436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fgfkg\" ","response":"range_response_count:1 size:9798"}
{"level":"info","ts":"2026-05-18T01:08:17.270376Z","caller":"traceutil/trace.go:171","msg":"trace[2049955270] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fgfkg; range_end:; response_count:1; response_revision:14797; }","duration":"178.698042ms","start":"2026-05-18T01:08:17.091655Z","end":"2026-05-18T01:08:17.270353Z","steps":["trace[2049955270] 'range keys from in-memory index tree'  (duration: 178.154741ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:08:17.358136Z","caller":"traceutil/trace.go:171","msg":"trace[313624778] linearizableReadLoop","detail":"{readStateIndex:15297; appliedIndex:15296; }","duration":"128.497985ms","start":"2026-05-18T01:08:17.229617Z","end":"2026-05-18T01:08:17.358115Z","steps":["trace[313624778] 'read index received'  (duration: 128.307681ms)","trace[313624778] 'applied index is now lower than readState.Index'  (duration: 189.074µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:08:17.358237Z","caller":"traceutil/trace.go:171","msg":"trace[936083149] transaction","detail":"{read_only:false; response_revision:14798; number_of_response:1; }","duration":"153.19613ms","start":"2026-05-18T01:08:17.205019Z","end":"2026-05-18T01:08:17.358216Z","steps":["trace[936083149] 'process raft request'  (duration: 152.923675ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.358289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.629027ms","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-05-18T01:08:17.358326Z","caller":"traceutil/trace.go:171","msg":"trace[720539359] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14798; }","duration":"128.724138ms","start":"2026-05-18T01:08:17.229592Z","end":"2026-05-18T01:08:17.358316Z","steps":["trace[720539359] 'agreement among raft nodes before linearized reading'  (duration: 128.630717ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.35838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.735372ms","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-05-18T01:08:17.358416Z","caller":"traceutil/trace.go:171","msg":"trace[332098088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14798; }","duration":"107.793943ms","start":"2026-05-18T01:08:17.250611Z","end":"2026-05-18T01:08:17.358405Z","steps":["trace[332098088] 'agreement among raft nodes before linearized reading'  (duration: 107.681681ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.358383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.605877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T01:08:17.358496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.20789ms","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-05-18T01:08:17.358533Z","caller":"traceutil/trace.go:171","msg":"trace[929450326] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14798; }","duration":"128.756699ms","start":"2026-05-18T01:08:17.229703Z","end":"2026-05-18T01:08:17.358459Z","steps":["trace[929450326] 'agreement among raft nodes before linearized reading'  (duration: 128.575246ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:08:17.358533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.633047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T01:08:17.358606Z","caller":"traceutil/trace.go:171","msg":"trace[1559054061] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14798; }","duration":"128.734079ms","start":"2026-05-18T01:08:17.229857Z","end":"2026-05-18T01:08:17.358592Z","steps":["trace[1559054061] 'agreement among raft nodes before linearized reading'  (duration: 128.573236ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:08:17.358538Z","caller":"traceutil/trace.go:171","msg":"trace[1797494404] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14798; }","duration":"118.288591ms","start":"2026-05-18T01:08:17.240237Z","end":"2026-05-18T01:08:17.358526Z","steps":["trace[1797494404] 'agreement among raft nodes before linearized reading'  (duration: 118.151149ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:10:16.445524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13082}
{"level":"info","ts":"2026-05-18T01:10:16.525084Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13082,"took":"75.271571ms","hash":4275920215,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31952896,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T01:10:16.525186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4275920215,"revision":13082,"compact-revision":11021}
{"level":"info","ts":"2026-05-18T01:13:59.201048Z","caller":"traceutil/trace.go:171","msg":"trace[280946154] transaction","detail":"{read_only:false; response_revision:17527; number_of_response:1; }","duration":"170.589089ms","start":"2026-05-18T01:13:59.030425Z","end":"2026-05-18T01:13:59.201015Z","steps":["trace[280946154] 'process raft request'  (duration: 170.367925ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:13:59.201222Z","caller":"traceutil/trace.go:171","msg":"trace[2056347319] linearizableReadLoop","detail":"{readStateIndex:18110; appliedIndex:18110; }","duration":"163.959533ms","start":"2026-05-18T01:13:59.037224Z","end":"2026-05-18T01:13:59.201183Z","steps":["trace[2056347319] 'read index received'  (duration: 163.941613ms)","trace[2056347319] 'applied index is now lower than readState.Index'  (duration: 14.92µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:13:59.201461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.215249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:13:59.201503Z","caller":"traceutil/trace.go:171","msg":"trace[538403787] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:17527; }","duration":"164.325351ms","start":"2026-05-18T01:13:59.037167Z","end":"2026-05-18T01:13:59.201493Z","steps":["trace[538403787] 'agreement among raft nodes before linearized reading'  (duration: 164.186177ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:13:59.204677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.001331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:13:59.20476Z","caller":"traceutil/trace.go:171","msg":"trace[333675227] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:0; response_revision:17529; }","duration":"167.120593ms","start":"2026-05-18T01:13:59.03762Z","end":"2026-05-18T01:13:59.204741Z","steps":["trace[333675227] 'agreement among raft nodes before linearized reading'  (duration: 166.96752ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:13:59.205713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.961205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:13:59.205771Z","caller":"traceutil/trace.go:171","msg":"trace[1820809317] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:17529; }","duration":"163.034416ms","start":"2026-05-18T01:13:59.042722Z","end":"2026-05-18T01:13:59.205757Z","steps":["trace[1820809317] 'agreement among raft nodes before linearized reading'  (duration: 162.954365ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:13:59.205964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.488345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:13:59.205995Z","caller":"traceutil/trace.go:171","msg":"trace[294276766] range","detail":"{range_begin:/registry/jobs/openstack/heat-rabbit-init; range_end:; response_count:0; response_revision:17529; }","duration":"163.521355ms","start":"2026-05-18T01:13:59.042465Z","end":"2026-05-18T01:13:59.205987Z","steps":["trace[294276766] 'agreement among raft nodes before linearized reading'  (duration: 163.471044ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:13:59.206122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.823881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:13:59.206151Z","caller":"traceutil/trace.go:171","msg":"trace[1071199751] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:17529; }","duration":"163.909182ms","start":"2026-05-18T01:13:59.042234Z","end":"2026-05-18T01:13:59.206143Z","steps":["trace[1071199751] 'agreement among raft nodes before linearized reading'  (duration: 163.861451ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:13:59.206277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.120167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:13:59.206316Z","caller":"traceutil/trace.go:171","msg":"trace[285538719] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:17529; }","duration":"164.174967ms","start":"2026-05-18T01:13:59.042133Z","end":"2026-05-18T01:13:59.206308Z","steps":["trace[285538719] 'agreement among raft nodes before linearized reading'  (duration: 164.122536ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:14:03.914673Z","caller":"traceutil/trace.go:171","msg":"trace[1953390882] transaction","detail":"{read_only:false; response_revision:17560; number_of_response:1; }","duration":"243.032085ms","start":"2026-05-18T01:14:03.671606Z","end":"2026-05-18T01:14:03.914638Z","steps":["trace[1953390882] 'process raft request'  (duration: 242.73017ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:15:16.451213Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15720}
{"level":"info","ts":"2026-05-18T01:15:16.549622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15720,"took":"94.684945ms","hash":4226978497,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":33939456,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-05-18T01:15:16.54968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4226978497,"revision":15720,"compact-revision":13082}
{"level":"info","ts":"2026-05-18T01:17:50.001093Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-18T01:17:50.00699Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003}
{"level":"info","ts":"2026-05-18T01:17:50.007284Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003}
{"level":"info","ts":"2026-05-18T01:18:36.09221Z","caller":"traceutil/trace.go:171","msg":"trace[1603096280] transaction","detail":"{read_only:false; response_revision:19598; number_of_response:1; }","duration":"119.344312ms","start":"2026-05-18T01:18:35.972839Z","end":"2026-05-18T01:18:36.092183Z","steps":["trace[1603096280] 'process raft request'  (duration: 119.153989ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:18:36.328242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.413516ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215480790 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:19586 > 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-05-18T01:18:36.328341Z","caller":"traceutil/trace.go:171","msg":"trace[332312614] linearizableReadLoop","detail":"{readStateIndex:20253; appliedIndex:20252; }","duration":"136.281902ms","start":"2026-05-18T01:18:36.192043Z","end":"2026-05-18T01:18:36.328325Z","steps":["trace[332312614] 'read index received'  (duration: 28.700952ms)","trace[332312614] 'applied index is now lower than readState.Index'  (duration: 107.57966ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:18:36.328686Z","caller":"traceutil/trace.go:171","msg":"trace[555468742] transaction","detail":"{read_only:false; response_revision:19599; number_of_response:1; }","duration":"170.712372ms","start":"2026-05-18T01:18:36.157924Z","end":"2026-05-18T01:18:36.328636Z","steps":["trace[555468742] 'process raft request'  (duration: 62.809946ms)","trace[555468742] 'compare'  (duration: 107.156622ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:18:36.328962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.860752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:18:36.32922Z","caller":"traceutil/trace.go:171","msg":"trace[592155606] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:19599; }","duration":"137.028786ms","start":"2026-05-18T01:18:36.192003Z","end":"2026-05-18T01:18:36.329032Z","steps":["trace[592155606] 'agreement among raft nodes before linearized reading'  (duration: 136.535386ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:18:36.375145Z","caller":"traceutil/trace.go:171","msg":"trace[1221212377] transaction","detail":"{read_only:false; response_revision:19600; number_of_response:1; }","duration":"159.859677ms","start":"2026-05-18T01:18:36.215254Z","end":"2026-05-18T01:18:36.375113Z","steps":["trace[1221212377] 'process raft request'  (duration: 159.576162ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:18:36.375408Z","caller":"traceutil/trace.go:171","msg":"trace[317582221] transaction","detail":"{read_only:false; response_revision:19601; number_of_response:1; }","duration":"157.94133ms","start":"2026-05-18T01:18:36.217439Z","end":"2026-05-18T01:18:36.37538Z","steps":["trace[317582221] 'process raft request'  (duration: 157.591334ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:18:36.375491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.271098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:18:36.375616Z","caller":"traceutil/trace.go:171","msg":"trace[1142821683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19601; }","duration":"183.460172ms","start":"2026-05-18T01:18:36.192125Z","end":"2026-05-18T01:18:36.375585Z","steps":["trace[1142821683] 'agreement among raft nodes before linearized reading'  (duration: 183.238348ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:18:36.656759Z","caller":"traceutil/trace.go:171","msg":"trace[1875775858] transaction","detail":"{read_only:false; response_revision:19602; number_of_response:1; }","duration":"103.877101ms","start":"2026-05-18T01:18:36.552857Z","end":"2026-05-18T01:18:36.656734Z","steps":["trace[1875775858] 'process raft request'  (duration: 103.737487ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:18:37.493143Z","caller":"traceutil/trace.go:171","msg":"trace[1518053598] transaction","detail":"{read_only:false; response_revision:19603; number_of_response:1; }","duration":"137.154719ms","start":"2026-05-18T01:18:37.355964Z","end":"2026-05-18T01:18:37.493118Z","steps":["trace[1518053598] 'process raft request'  (duration: 136.997916ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:20:16.458151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18212}
{"level":"info","ts":"2026-05-18T01:20:16.596426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18212,"took":"134.223963ms","hash":2534672140,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":30117888,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-05-18T01:20:16.59651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2534672140,"revision":18212,"compact-revision":15720}
{"level":"info","ts":"2026-05-18T01:22:16.137574Z","caller":"traceutil/trace.go:171","msg":"trace[969140812] transaction","detail":"{read_only:false; response_revision:21947; number_of_response:1; }","duration":"196.666401ms","start":"2026-05-18T01:22:15.940872Z","end":"2026-05-18T01:22:16.137539Z","steps":["trace[969140812] 'process raft request'  (duration: 196.469057ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:22:16.425516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.757355ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215487738 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager.18b08450fa1e0c5d\" mod_revision:0 > success:<request_put:<key:\"/registry/events/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager.18b08450fa1e0c5d\" value_size:769 lease:4487166569360711328 >> failure:<>>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:22:16.425639Z","caller":"traceutil/trace.go:171","msg":"trace[885449649] transaction","detail":"{read_only:false; response_revision:21948; number_of_response:1; }","duration":"282.286327ms","start":"2026-05-18T01:22:16.143333Z","end":"2026-05-18T01:22:16.425619Z","steps":["trace[885449649] 'process raft request'  (duration: 158.350438ms)","trace[885449649] 'compare'  (duration: 123.420759ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:22:16.428156Z","caller":"traceutil/trace.go:171","msg":"trace[1430246162] transaction","detail":"{read_only:false; response_revision:21950; number_of_response:1; }","duration":"282.350598ms","start":"2026-05-18T01:22:16.145764Z","end":"2026-05-18T01:22:16.428115Z","steps":["trace[1430246162] 'process raft request'  (duration: 282.122054ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:22:16.428205Z","caller":"traceutil/trace.go:171","msg":"trace[674727062] transaction","detail":"{read_only:false; response_revision:21949; number_of_response:1; }","duration":"283.773385ms","start":"2026-05-18T01:22:16.144381Z","end":"2026-05-18T01:22:16.428154Z","steps":["trace[674727062] 'process raft request'  (duration: 283.316226ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:22:16.428106Z","caller":"traceutil/trace.go:171","msg":"trace[1502258802] linearizableReadLoop","detail":"{readStateIndex:22674; appliedIndex:22671; }","duration":"246.252707ms","start":"2026-05-18T01:22:16.181802Z","end":"2026-05-18T01:22:16.428055Z","steps":["trace[1502258802] 'read index received'  (duration: 119.895933ms)","trace[1502258802] 'applied index is now lower than readState.Index'  (duration: 126.355014ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:22:16.42829Z","caller":"traceutil/trace.go:171","msg":"trace[1425927149] transaction","detail":"{read_only:false; response_revision:21951; number_of_response:1; }","duration":"220.155313ms","start":"2026-05-18T01:22:16.208093Z","end":"2026-05-18T01:22:16.428248Z","steps":["trace[1425927149] 'process raft request'  (duration: 219.888658ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:22:16.428484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.631514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:22:16.428626Z","caller":"traceutil/trace.go:171","msg":"trace[52105752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21951; }","duration":"246.849788ms","start":"2026-05-18T01:22:16.181766Z","end":"2026-05-18T01:22:16.428616Z","steps":["trace[52105752] 'agreement among raft nodes before linearized reading'  (duration: 246.618794ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:16.46941Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20467}
{"level":"info","ts":"2026-05-18T01:25:16.573907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20467,"took":"101.535257ms","hash":850197202,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":43253760,"current-db-size-in-use":"43 MB"}
{"level":"info","ts":"2026-05-18T01:25:16.573967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":850197202,"revision":20467,"compact-revision":18212}
{"level":"info","ts":"2026-05-18T01:25:19.854919Z","caller":"traceutil/trace.go:171","msg":"trace[926545986] transaction","detail":"{read_only:false; response_revision:23855; number_of_response:1; }","duration":"110.068867ms","start":"2026-05-18T01:25:19.744829Z","end":"2026-05-18T01:25:19.854898Z","steps":["trace[926545986] 'process raft request'  (duration: 102.83445ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:19.952996Z","caller":"traceutil/trace.go:171","msg":"trace[569360374] linearizableReadLoop","detail":"{readStateIndex:24630; appliedIndex:24626; }","duration":"150.584111ms","start":"2026-05-18T01:25:19.802395Z","end":"2026-05-18T01:25:19.952979Z","steps":["trace[569360374] 'read index received'  (duration: 45.290164ms)","trace[569360374] 'applied index is now lower than readState.Index'  (duration: 105.293237ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:19.953088Z","caller":"traceutil/trace.go:171","msg":"trace[312159483] transaction","detail":"{read_only:false; response_revision:23859; number_of_response:1; }","duration":"135.690201ms","start":"2026-05-18T01:25:19.817387Z","end":"2026-05-18T01:25:19.953077Z","steps":["trace[312159483] 'process raft request'  (duration: 135.530618ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:19.953103Z","caller":"traceutil/trace.go:171","msg":"trace[659264090] transaction","detail":"{read_only:false; response_revision:23858; number_of_response:1; }","duration":"155.27584ms","start":"2026-05-18T01:25:19.797805Z","end":"2026-05-18T01:25:19.95308Z","steps":["trace[659264090] 'process raft request'  (duration: 155.082687ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:19.95314Z","caller":"traceutil/trace.go:171","msg":"trace[1609231300] transaction","detail":"{read_only:false; response_revision:23857; number_of_response:1; }","duration":"159.716734ms","start":"2026-05-18T01:25:19.79339Z","end":"2026-05-18T01:25:19.953107Z","steps":["trace[1609231300] 'process raft request'  (duration: 159.472809ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:19.95303Z","caller":"traceutil/trace.go:171","msg":"trace[1967132644] transaction","detail":"{read_only:false; response_revision:23856; number_of_response:1; }","duration":"208.060846ms","start":"2026-05-18T01:25:19.744923Z","end":"2026-05-18T01:25:19.952984Z","steps":["trace[1967132644] 'process raft request'  (duration: 207.821681ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:19.953268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.865326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:137"}
{"level":"info","ts":"2026-05-18T01:25:19.953281Z","caller":"traceutil/trace.go:171","msg":"trace[1421107243] transaction","detail":"{read_only:false; response_revision:23860; number_of_response:1; }","duration":"135.828093ms","start":"2026-05-18T01:25:19.817417Z","end":"2026-05-18T01:25:19.953245Z","steps":["trace[1421107243] 'process raft request'  (duration: 135.537658ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:19.953334Z","caller":"traceutil/trace.go:171","msg":"trace[300385978] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:23860; }","duration":"150.977589ms","start":"2026-05-18T01:25:19.802343Z","end":"2026-05-18T01:25:19.95332Z","steps":["trace[300385978] 'agreement among raft nodes before linearized reading'  (duration: 150.784835ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:19.953269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.478267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"}
{"level":"info","ts":"2026-05-18T01:25:19.953416Z","caller":"traceutil/trace.go:171","msg":"trace[1886175607] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23860; }","duration":"125.664721ms","start":"2026-05-18T01:25:19.827737Z","end":"2026-05-18T01:25:19.953402Z","steps":["trace[1886175607] 'agreement among raft nodes before linearized reading'  (duration: 125.425707ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:20.158208Z","caller":"traceutil/trace.go:171","msg":"trace[1796041074] linearizableReadLoop","detail":"{readStateIndex:24634; appliedIndex:24632; }","duration":"123.889757ms","start":"2026-05-18T01:25:20.034298Z","end":"2026-05-18T01:25:20.158188Z","steps":["trace[1796041074] 'read index received'  (duration: 21.699149ms)","trace[1796041074] 'applied index is now lower than readState.Index'  (duration: 102.189228ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:20.158328Z","caller":"traceutil/trace.go:171","msg":"trace[1053806358] transaction","detail":"{read_only:false; response_revision:23861; number_of_response:1; }","duration":"200.665896ms","start":"2026-05-18T01:25:19.957651Z","end":"2026-05-18T01:25:20.158317Z","steps":["trace[1053806358] 'process raft request'  (duration: 200.37572ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:20.158629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.309435ms","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-05-18T01:25:20.158718Z","caller":"traceutil/trace.go:171","msg":"trace[417495198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23861; }","duration":"124.439568ms","start":"2026-05-18T01:25:20.034255Z","end":"2026-05-18T01:25:20.158694Z","steps":["trace[417495198] 'agreement among raft nodes before linearized reading'  (duration: 124.065941ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:20.446224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.345403ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492316 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.204.45.44\" mod_revision:23745 > success:<request_put:<key:\"/registry/masterleases/199.204.45.44\" value_size:67 lease:4487166569360716505 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.44\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:25:20.446416Z","caller":"traceutil/trace.go:171","msg":"trace[194644051] transaction","detail":"{read_only:false; response_revision:23862; number_of_response:1; }","duration":"296.899712ms","start":"2026-05-18T01:25:20.149471Z","end":"2026-05-18T01:25:20.446371Z","steps":["trace[194644051] 'process raft request'  (duration: 160.295474ms)","trace[194644051] 'compare'  (duration: 136.17753ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:20.446532Z","caller":"traceutil/trace.go:171","msg":"trace[321326136] linearizableReadLoop","detail":"{readStateIndex:24635; appliedIndex:24634; }","duration":"288.231659ms","start":"2026-05-18T01:25:20.158265Z","end":"2026-05-18T01:25:20.446497Z","steps":["trace[321326136] 'read index received'  (duration: 151.58535ms)","trace[321326136] 'applied index is now lower than readState.Index'  (duration: 136.643519ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:20.446452Z","caller":"traceutil/trace.go:171","msg":"trace[1102901954] transaction","detail":"{read_only:false; response_revision:23863; number_of_response:1; }","duration":"282.920198ms","start":"2026-05-18T01:25:20.163509Z","end":"2026-05-18T01:25:20.446429Z","steps":["trace[1102901954] 'process raft request'  (duration: 282.839627ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:20.446686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.856598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:20.446727Z","caller":"traceutil/trace.go:171","msg":"trace[1300508334] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:23863; }","duration":"361.93231ms","start":"2026-05-18T01:25:20.084782Z","end":"2026-05-18T01:25:20.446714Z","steps":["trace[1300508334] 'agreement among raft nodes before linearized reading'  (duration: 361.852648ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:20.446776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:20.084762Z","time spent":"362.003911ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T01:25:20.446765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.853782ms","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-05-18T01:25:20.446763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.914748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:20.446843Z","caller":"traceutil/trace.go:171","msg":"trace[1068295733] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:23863; }","duration":"216.957864ms","start":"2026-05-18T01:25:20.229863Z","end":"2026-05-18T01:25:20.446821Z","steps":["trace[1068295733] 'agreement among raft nodes before linearized reading'  (duration: 216.811721ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:20.446852Z","caller":"traceutil/trace.go:171","msg":"trace[1330835912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23863; }","duration":"265.053711ms","start":"2026-05-18T01:25:20.181776Z","end":"2026-05-18T01:25:20.44683Z","steps":["trace[1330835912] 'agreement among raft nodes before linearized reading'  (duration: 264.893738ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:25.02283Z","caller":"traceutil/trace.go:171","msg":"trace[1735306006] transaction","detail":"{read_only:false; response_revision:23902; number_of_response:1; }","duration":"170.777633ms","start":"2026-05-18T01:25:24.852029Z","end":"2026-05-18T01:25:25.022806Z","steps":["trace[1735306006] 'process raft request'  (duration: 169.483258ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:25.493801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.150078ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492410 > lease_revoke:<id:3e459e3886c0f1a9>","response":"size:30"}
{"level":"info","ts":"2026-05-18T01:25:25.494071Z","caller":"traceutil/trace.go:171","msg":"trace[944785843] linearizableReadLoop","detail":"{readStateIndex:24676; appliedIndex:24674; }","duration":"534.966644ms","start":"2026-05-18T01:25:24.959085Z","end":"2026-05-18T01:25:25.494052Z","steps":["trace[944785843] 'read index received'  (duration: 62.348076ms)","trace[944785843] 'applied index is now lower than readState.Index'  (duration: 472.617478ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:25.494497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.369382ms","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-05-18T01:25:25.494567Z","caller":"traceutil/trace.go:171","msg":"trace[349017673] 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:23902; }","duration":"535.505604ms","start":"2026-05-18T01:25:24.959045Z","end":"2026-05-18T01:25:25.494551Z","steps":["trace[349017673] 'agreement among raft nodes before linearized reading'  (duration: 535.125137ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:25.494604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:24.959029Z","time spent":"535.566815ms","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "}
{"level":"info","ts":"2026-05-18T01:25:25.495415Z","caller":"traceutil/trace.go:171","msg":"trace[1230038293] transaction","detail":"{read_only:false; response_revision:23903; number_of_response:1; }","duration":"300.287276ms","start":"2026-05-18T01:25:25.195115Z","end":"2026-05-18T01:25:25.495402Z","steps":["trace[1230038293] 'process raft request'  (duration: 298.795259ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:25.495495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:25.1951Z","time spent":"300.346377ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:23882 > 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":"warn","ts":"2026-05-18T01:25:25.617076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.589545ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492412 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:23883 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:442 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:25:25.617199Z","caller":"traceutil/trace.go:171","msg":"trace[634852147] linearizableReadLoop","detail":"{readStateIndex:24678; appliedIndex:24676; }","duration":"123.054262ms","start":"2026-05-18T01:25:25.494123Z","end":"2026-05-18T01:25:25.617177Z","steps":["trace[634852147] 'read index received'  (duration: 57.911µs)","trace[634852147] 'applied index is now lower than readState.Index'  (duration: 122.994771ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:25.617508Z","caller":"traceutil/trace.go:171","msg":"trace[297475040] transaction","detail":"{read_only:false; response_revision:23904; number_of_response:1; }","duration":"322.26372ms","start":"2026-05-18T01:25:25.295223Z","end":"2026-05-18T01:25:25.617487Z","steps":["trace[297475040] 'process raft request'  (duration: 200.169686ms)","trace[297475040] 'compare'  (duration: 121.059075ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:25.617616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:25.295206Z","time spent":"322.357103ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:23883 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:442 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"warn","ts":"2026-05-18T01:25:25.6179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.61346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:25.617971Z","caller":"traceutil/trace.go:171","msg":"trace[897068099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:23904; }","duration":"239.688632ms","start":"2026-05-18T01:25:25.378263Z","end":"2026-05-18T01:25:25.617952Z","steps":["trace[897068099] 'agreement among raft nodes before linearized reading'  (duration: 239.56981ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:25.618236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.580688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:25.618283Z","caller":"traceutil/trace.go:171","msg":"trace[135908830] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:23904; }","duration":"418.630739ms","start":"2026-05-18T01:25:25.199641Z","end":"2026-05-18T01:25:25.618272Z","steps":["trace[135908830] 'agreement among raft nodes before linearized reading'  (duration: 418.548777ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:25.618353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:25.199594Z","time spent":"418.744251ms","remote":"127.0.0.1:51182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":30,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T01:25:25.618642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.23893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:25.6187Z","caller":"traceutil/trace.go:171","msg":"trace[885142693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23904; }","duration":"437.322511ms","start":"2026-05-18T01:25:25.181364Z","end":"2026-05-18T01:25:25.618686Z","steps":["trace[885142693] 'agreement among raft nodes before linearized reading'  (duration: 437.23871ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:25.618747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:25.181348Z","time spent":"437.389513ms","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-05-18T01:25:25.735467Z","caller":"traceutil/trace.go:171","msg":"trace[1564196261] linearizableReadLoop","detail":"{readStateIndex:24679; appliedIndex:24678; }","duration":"111.414403ms","start":"2026-05-18T01:25:25.624032Z","end":"2026-05-18T01:25:25.735446Z","steps":["trace[1564196261] 'read index received'  (duration: 109.979557ms)","trace[1564196261] 'applied index is now lower than readState.Index'  (duration: 1.433786ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:25.735484Z","caller":"traceutil/trace.go:171","msg":"trace[883910378] transaction","detail":"{read_only:false; response_revision:23905; number_of_response:1; }","duration":"112.091355ms","start":"2026-05-18T01:25:25.623369Z","end":"2026-05-18T01:25:25.735461Z","steps":["trace[883910378] 'process raft request'  (duration: 110.764591ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:25.735611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.563936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"}
{"level":"info","ts":"2026-05-18T01:25:25.735644Z","caller":"traceutil/trace.go:171","msg":"trace[838894912] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23905; }","duration":"111.607426ms","start":"2026-05-18T01:25:25.624022Z","end":"2026-05-18T01:25:25.735629Z","steps":["trace[838894912] 'agreement among raft nodes before linearized reading'  (duration: 111.504235ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:26.013668Z","caller":"traceutil/trace.go:171","msg":"trace[1648331990] linearizableReadLoop","detail":"{readStateIndex:24680; appliedIndex:24679; }","duration":"218.969992ms","start":"2026-05-18T01:25:25.794674Z","end":"2026-05-18T01:25:26.013644Z","steps":["trace[1648331990] 'read index received'  (duration: 218.776538ms)","trace[1648331990] 'applied index is now lower than readState.Index'  (duration: 192.564µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:26.013723Z","caller":"traceutil/trace.go:171","msg":"trace[1504673944] transaction","detail":"{read_only:false; response_revision:23906; number_of_response:1; }","duration":"245.990141ms","start":"2026-05-18T01:25:25.767705Z","end":"2026-05-18T01:25:26.013695Z","steps":["trace[1504673944] 'process raft request'  (duration: 245.765357ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:26.013842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.142535ms","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":"info","ts":"2026-05-18T01:25:26.0139Z","caller":"traceutil/trace.go:171","msg":"trace[320301194] 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:23906; }","duration":"219.219286ms","start":"2026-05-18T01:25:25.794667Z","end":"2026-05-18T01:25:26.013886Z","steps":["trace[320301194] 'agreement among raft nodes before linearized reading'  (duration: 219.105804ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:26.024855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.892151ms","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-05-18T01:25:26.024901Z","caller":"traceutil/trace.go:171","msg":"trace[1832541440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23910; }","duration":"146.978733ms","start":"2026-05-18T01:25:25.877909Z","end":"2026-05-18T01:25:26.024888Z","steps":["trace[1832541440] 'agreement among raft nodes before linearized reading'  (duration: 146.879261ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:26.305326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.167998ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492428 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:23893 > 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:18"}
{"level":"info","ts":"2026-05-18T01:25:26.305643Z","caller":"traceutil/trace.go:171","msg":"trace[1421428035] linearizableReadLoop","detail":"{readStateIndex:24686; appliedIndex:24684; }","duration":"275.622061ms","start":"2026-05-18T01:25:26.030005Z","end":"2026-05-18T01:25:26.305627Z","steps":["trace[1421428035] 'read index received'  (duration: 130.001674ms)","trace[1421428035] 'applied index is now lower than readState.Index'  (duration: 145.619377ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:26.305785Z","caller":"traceutil/trace.go:171","msg":"trace[1094859509] transaction","detail":"{read_only:false; response_revision:23911; number_of_response:1; }","duration":"277.112258ms","start":"2026-05-18T01:25:26.02865Z","end":"2026-05-18T01:25:26.305762Z","steps":["trace[1094859509] 'process raft request'  (duration: 131.356809ms)","trace[1094859509] 'compare'  (duration: 145.060596ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:26.305673Z","caller":"traceutil/trace.go:171","msg":"trace[617552700] transaction","detail":"{read_only:false; response_revision:23912; number_of_response:1; }","duration":"276.787273ms","start":"2026-05-18T01:25:26.02885Z","end":"2026-05-18T01:25:26.305638Z","steps":["trace[617552700] 'process raft request'  (duration: 276.580228ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:26.30575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.742804ms","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-05-18T01:25:26.306124Z","caller":"traceutil/trace.go:171","msg":"trace[1895703790] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:23912; }","duration":"276.147191ms","start":"2026-05-18T01:25:26.029962Z","end":"2026-05-18T01:25:26.306109Z","steps":["trace[1895703790] 'agreement among raft nodes before linearized reading'  (duration: 275.734743ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:26.305965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.171844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:26.30623Z","caller":"traceutil/trace.go:171","msg":"trace[1672343385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23912; }","duration":"124.473849ms","start":"2026-05-18T01:25:26.181725Z","end":"2026-05-18T01:25:26.306198Z","steps":["trace[1672343385] 'agreement among raft nodes before linearized reading'  (duration: 124.153824ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:26.590267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.435531ms","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-05-18T01:25:26.590356Z","caller":"traceutil/trace.go:171","msg":"trace[932500638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23912; }","duration":"121.579624ms","start":"2026-05-18T01:25:26.468759Z","end":"2026-05-18T01:25:26.590338Z","steps":["trace[932500638] 'range keys from in-memory index tree'  (duration: 121.249248ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:26.657439Z","caller":"traceutil/trace.go:171","msg":"trace[1377560073] transaction","detail":"{read_only:false; response_revision:23913; number_of_response:1; }","duration":"165.052864ms","start":"2026-05-18T01:25:26.492357Z","end":"2026-05-18T01:25:26.65741Z","steps":["trace[1377560073] 'process raft request'  (duration: 164.524725ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:26.743032Z","caller":"traceutil/trace.go:171","msg":"trace[748562255] linearizableReadLoop","detail":"{readStateIndex:24689; appliedIndex:24686; }","duration":"108.720912ms","start":"2026-05-18T01:25:26.63429Z","end":"2026-05-18T01:25:26.743011Z","steps":["trace[748562255] 'read index received'  (duration: 22.530645ms)","trace[748562255] 'applied index is now lower than readState.Index'  (duration: 86.189447ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:26.74313Z","caller":"traceutil/trace.go:171","msg":"trace[43409144] transaction","detail":"{read_only:false; response_revision:23915; number_of_response:1; }","duration":"115.986519ms","start":"2026-05-18T01:25:26.627129Z","end":"2026-05-18T01:25:26.743115Z","steps":["trace[43409144] 'process raft request'  (duration: 115.846256ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:26.743107Z","caller":"traceutil/trace.go:171","msg":"trace[2142760592] transaction","detail":"{read_only:false; response_revision:23914; number_of_response:1; }","duration":"148.602114ms","start":"2026-05-18T01:25:26.594468Z","end":"2026-05-18T01:25:26.74307Z","steps":["trace[2142760592] 'process raft request'  (duration: 138.842329ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:26.743207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.896195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:26.743531Z","caller":"traceutil/trace.go:171","msg":"trace[1420740357] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:23915; }","duration":"109.266832ms","start":"2026-05-18T01:25:26.634255Z","end":"2026-05-18T01:25:26.743521Z","steps":["trace[1420740357] 'agreement among raft nodes before linearized reading'  (duration: 108.902506ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:27.019801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.34564ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492439 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:23901 > 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-05-18T01:25:27.019917Z","caller":"traceutil/trace.go:171","msg":"trace[1855679096] linearizableReadLoop","detail":"{readStateIndex:24690; appliedIndex:24689; }","duration":"236.594895ms","start":"2026-05-18T01:25:26.783302Z","end":"2026-05-18T01:25:27.019897Z","steps":["trace[1855679096] 'read index received'  (duration: 89.04813ms)","trace[1855679096] 'applied index is now lower than readState.Index'  (duration: 147.545434ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:27.020045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.741818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:27.020101Z","caller":"traceutil/trace.go:171","msg":"trace[1454724960] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:23916; }","duration":"236.801069ms","start":"2026-05-18T01:25:26.783283Z","end":"2026-05-18T01:25:27.020084Z","steps":["trace[1454724960] 'agreement among raft nodes before linearized reading'  (duration: 236.672097ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:27.020347Z","caller":"traceutil/trace.go:171","msg":"trace[1655448501] transaction","detail":"{read_only:false; response_revision:23916; number_of_response:1; }","duration":"272.210957ms","start":"2026-05-18T01:25:26.748084Z","end":"2026-05-18T01:25:27.020295Z","steps":["trace[1655448501] 'process raft request'  (duration: 124.272365ms)","trace[1655448501] 'compare'  (duration: 147.171617ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:27.03155Z","caller":"traceutil/trace.go:171","msg":"trace[1436965940] transaction","detail":"{read_only:false; response_revision:23917; number_of_response:1; }","duration":"223.143441ms","start":"2026-05-18T01:25:26.808386Z","end":"2026-05-18T01:25:27.03153Z","steps":["trace[1436965940] 'process raft request'  (duration: 222.990298ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:27.369299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.014259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:27.369375Z","caller":"traceutil/trace.go:171","msg":"trace[1340271594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23917; }","duration":"187.126431ms","start":"2026-05-18T01:25:27.182231Z","end":"2026-05-18T01:25:27.369357Z","steps":["trace[1340271594] 'range keys from in-memory index tree'  (duration: 186.871635ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:27.975936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.526254ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492451 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:23905 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:25:27.976052Z","caller":"traceutil/trace.go:171","msg":"trace[678997908] transaction","detail":"{read_only:false; response_revision:23920; number_of_response:1; }","duration":"230.810495ms","start":"2026-05-18T01:25:27.745218Z","end":"2026-05-18T01:25:27.976029Z","steps":["trace[678997908] 'process raft request'  (duration: 101.108168ms)","trace[678997908] 'compare'  (duration: 129.28481ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:28.121154Z","caller":"traceutil/trace.go:171","msg":"trace[2020150294] transaction","detail":"{read_only:false; response_revision:23921; number_of_response:1; }","duration":"100.637549ms","start":"2026-05-18T01:25:28.020488Z","end":"2026-05-18T01:25:28.121126Z","steps":["trace[2020150294] 'process raft request'  (duration: 99.279143ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:28.413917Z","caller":"traceutil/trace.go:171","msg":"trace[934080082] transaction","detail":"{read_only:false; response_revision:23923; number_of_response:1; }","duration":"383.741831ms","start":"2026-05-18T01:25:28.030156Z","end":"2026-05-18T01:25:28.413897Z","steps":["trace[934080082] 'process raft request'  (duration: 383.68735ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.414041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:28.030145Z","time spent":"383.829542ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:23909 > 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\" > >"}
{"level":"info","ts":"2026-05-18T01:25:28.414224Z","caller":"traceutil/trace.go:171","msg":"trace[1320534456] transaction","detail":"{read_only:false; response_revision:23922; number_of_response:1; }","duration":"384.21167ms","start":"2026-05-18T01:25:28.030005Z","end":"2026-05-18T01:25:28.414216Z","steps":["trace[1320534456] 'process raft request'  (duration: 376.004175ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.414267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:28.029959Z","time spent":"384.28653ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:23907 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:504 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >"}
{"level":"info","ts":"2026-05-18T01:25:28.414669Z","caller":"traceutil/trace.go:171","msg":"trace[1312631718] linearizableReadLoop","detail":"{readStateIndex:24699; appliedIndex:24695; }","duration":"189.047346ms","start":"2026-05-18T01:25:28.225611Z","end":"2026-05-18T01:25:28.414658Z","steps":["trace[1312631718] 'read index received'  (duration: 180.374483ms)","trace[1312631718] 'applied index is now lower than readState.Index'  (duration: 8.672113ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:28.414734Z","caller":"traceutil/trace.go:171","msg":"trace[2125109743] transaction","detail":"{read_only:false; response_revision:23924; number_of_response:1; }","duration":"384.585226ms","start":"2026-05-18T01:25:28.030144Z","end":"2026-05-18T01:25:28.414729Z","steps":["trace[2125109743] 'process raft request'  (duration: 383.727611ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.41477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:28.030115Z","time spent":"384.632027ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:23908 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-05-18T01:25:28.414866Z","caller":"traceutil/trace.go:171","msg":"trace[2101116914] transaction","detail":"{read_only:false; response_revision:23925; number_of_response:1; }","duration":"384.151828ms","start":"2026-05-18T01:25:28.030708Z","end":"2026-05-18T01:25:28.41486Z","steps":["trace[2101116914] 'process raft request'  (duration: 383.903093ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.414899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:28.030693Z","time spent":"384.186939ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:23910 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-05-18T01:25:28.415016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.415025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:28.415039Z","caller":"traceutil/trace.go:171","msg":"trace[1537823810] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:23925; }","duration":"189.439455ms","start":"2026-05-18T01:25:28.225592Z","end":"2026-05-18T01:25:28.415032Z","steps":["trace[1537823810] 'agreement among raft nodes before linearized reading'  (duration: 189.378174ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.415198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.561643ms","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-05-18T01:25:28.415217Z","caller":"traceutil/trace.go:171","msg":"trace[1387188375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23925; }","duration":"104.578363ms","start":"2026-05-18T01:25:28.310631Z","end":"2026-05-18T01:25:28.41521Z","steps":["trace[1387188375] 'agreement among raft nodes before linearized reading'  (duration: 104.499242ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.415508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.812688ms","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-05-18T01:25:28.415527Z","caller":"traceutil/trace.go:171","msg":"trace[1980700537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23925; }","duration":"104.875999ms","start":"2026-05-18T01:25:28.310645Z","end":"2026-05-18T01:25:28.415521Z","steps":["trace[1980700537] 'agreement among raft nodes before linearized reading'  (duration: 104.821878ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:28.566011Z","caller":"traceutil/trace.go:171","msg":"trace[136313496] transaction","detail":"{read_only:false; response_revision:23927; number_of_response:1; }","duration":"142.534518ms","start":"2026-05-18T01:25:28.423451Z","end":"2026-05-18T01:25:28.565986Z","steps":["trace[136313496] 'process raft request'  (duration: 138.720097ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:28.78734Z","caller":"traceutil/trace.go:171","msg":"trace[569113765] transaction","detail":"{read_only:false; response_revision:23928; number_of_response:1; }","duration":"222.552719ms","start":"2026-05-18T01:25:28.564731Z","end":"2026-05-18T01:25:28.787284Z","steps":["trace[569113765] 'process raft request'  (duration: 221.821486ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.78762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.32682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4b42dcd2-7f50-4c0c-8624-bf7f1dd91dc3\" ","response":"range_response_count:1 size:1323"}
{"level":"info","ts":"2026-05-18T01:25:28.787677Z","caller":"traceutil/trace.go:171","msg":"trace[1451648724] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4b42dcd2-7f50-4c0c-8624-bf7f1dd91dc3; range_end:; response_count:1; response_revision:23928; }","duration":"218.434661ms","start":"2026-05-18T01:25:28.569228Z","end":"2026-05-18T01:25:28.787663Z","steps":["trace[1451648724] 'agreement among raft nodes before linearized reading'  (duration: 218.252637ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:28.787434Z","caller":"traceutil/trace.go:171","msg":"trace[1594224813] linearizableReadLoop","detail":"{readStateIndex:24702; appliedIndex:24701; }","duration":"217.854669ms","start":"2026-05-18T01:25:28.569285Z","end":"2026-05-18T01:25:28.78714Z","steps":["trace[1594224813] 'read index received'  (duration: 217.36446ms)","trace[1594224813] 'applied index is now lower than readState.Index'  (duration: 488.979µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:28.865371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.613559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:28.865408Z","caller":"traceutil/trace.go:171","msg":"trace[58512029] transaction","detail":"{read_only:false; response_revision:23929; number_of_response:1; }","duration":"116.948146ms","start":"2026-05-18T01:25:28.748431Z","end":"2026-05-18T01:25:28.865379Z","steps":["trace[58512029] 'process raft request'  (duration: 116.768293ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:28.865432Z","caller":"traceutil/trace.go:171","msg":"trace[1186194243] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:23929; }","duration":"196.729062ms","start":"2026-05-18T01:25:28.668688Z","end":"2026-05-18T01:25:28.865417Z","steps":["trace[1186194243] 'agreement among raft nodes before linearized reading'  (duration: 196.604459ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:28.865599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.172259ms","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-05-18T01:25:28.865794Z","caller":"traceutil/trace.go:171","msg":"trace[1844395151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23929; }","duration":"117.415344ms","start":"2026-05-18T01:25:28.748359Z","end":"2026-05-18T01:25:28.865774Z","steps":["trace[1844395151] 'agreement among raft nodes before linearized reading'  (duration: 117.027837ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:29.042905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.576247ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492468 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:23914 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:25:29.043063Z","caller":"traceutil/trace.go:171","msg":"trace[466987628] linearizableReadLoop","detail":"{readStateIndex:24704; appliedIndex:24703; }","duration":"147.341091ms","start":"2026-05-18T01:25:28.895701Z","end":"2026-05-18T01:25:29.043042Z","steps":["trace[466987628] 'read index received'  (duration: 52.511µs)","trace[466987628] 'applied index is now lower than readState.Index'  (duration: 147.28741ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:29.043076Z","caller":"traceutil/trace.go:171","msg":"trace[1289942535] transaction","detail":"{read_only:false; response_revision:23930; number_of_response:1; }","duration":"172.809211ms","start":"2026-05-18T01:25:28.870243Z","end":"2026-05-18T01:25:29.043053Z","steps":["trace[1289942535] 'process raft request'  (duration: 17.992509ms)","trace[1289942535] 'compare'  (duration: 154.422215ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:29.043281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.559995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-05-18T01:25:29.043361Z","caller":"traceutil/trace.go:171","msg":"trace[396258524] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23930; }","duration":"147.690797ms","start":"2026-05-18T01:25:28.895653Z","end":"2026-05-18T01:25:29.043344Z","steps":["trace[396258524] 'agreement among raft nodes before linearized reading'  (duration: 147.463623ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:29.333386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.354511ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492472 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:23916 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:25:29.33347Z","caller":"traceutil/trace.go:171","msg":"trace[99608139] linearizableReadLoop","detail":"{readStateIndex:24705; appliedIndex:24704; }","duration":"285.275992ms","start":"2026-05-18T01:25:29.048181Z","end":"2026-05-18T01:25:29.333457Z","steps":["trace[99608139] 'read index received'  (duration: 138.723827ms)","trace[99608139] 'applied index is now lower than readState.Index'  (duration: 146.551355ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:29.333545Z","caller":"traceutil/trace.go:171","msg":"trace[1575240796] transaction","detail":"{read_only:false; response_revision:23931; number_of_response:1; }","duration":"285.796712ms","start":"2026-05-18T01:25:29.047741Z","end":"2026-05-18T01:25:29.333537Z","steps":["trace[1575240796] 'process raft request'  (duration: 139.222967ms)","trace[1575240796] 'compare'  (duration: 146.259469ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:29.333648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.547946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:29.333708Z","caller":"traceutil/trace.go:171","msg":"trace[1567393400] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:23931; }","duration":"100.612657ms","start":"2026-05-18T01:25:29.233079Z","end":"2026-05-18T01:25:29.333691Z","steps":["trace[1567393400] 'agreement among raft nodes before linearized reading'  (duration: 100.512255ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:29.333729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.503967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-18T01:25:29.333638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.722929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-18T01:25:29.33385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.637629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"}
{"level":"info","ts":"2026-05-18T01:25:29.333931Z","caller":"traceutil/trace.go:171","msg":"trace[1740783135] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23931; }","duration":"285.747941ms","start":"2026-05-18T01:25:29.048164Z","end":"2026-05-18T01:25:29.333912Z","steps":["trace[1740783135] 'agreement among raft nodes before linearized reading'  (duration: 285.484805ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:29.33383Z","caller":"traceutil/trace.go:171","msg":"trace[1010276888] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:23931; }","duration":"134.663471ms","start":"2026-05-18T01:25:29.199142Z","end":"2026-05-18T01:25:29.333806Z","steps":["trace[1010276888] 'agreement among raft nodes before linearized reading'  (duration: 134.481877ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:29.333899Z","caller":"traceutil/trace.go:171","msg":"trace[448571893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23931; }","duration":"155.026996ms","start":"2026-05-18T01:25:29.178849Z","end":"2026-05-18T01:25:29.333876Z","steps":["trace[448571893] 'agreement among raft nodes before linearized reading'  (duration: 154.726759ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:29.638863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.543589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"}
{"level":"info","ts":"2026-05-18T01:25:29.638946Z","caller":"traceutil/trace.go:171","msg":"trace[111320953] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:23931; }","duration":"133.668322ms","start":"2026-05-18T01:25:29.505257Z","end":"2026-05-18T01:25:29.638926Z","steps":["trace[111320953] 'range keys from in-memory index tree'  (duration: 133.366286ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:29.63901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.539493ms","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-05-18T01:25:29.639111Z","caller":"traceutil/trace.go:171","msg":"trace[1561748425] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23931; }","duration":"157.684395ms","start":"2026-05-18T01:25:29.481404Z","end":"2026-05-18T01:25:29.639089Z","steps":["trace[1561748425] 'range keys from in-memory index tree'  (duration: 157.444521ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:29.645475Z","caller":"traceutil/trace.go:171","msg":"trace[393007459] transaction","detail":"{read_only:false; response_revision:23932; number_of_response:1; }","duration":"125.882555ms","start":"2026-05-18T01:25:29.519557Z","end":"2026-05-18T01:25:29.645439Z","steps":["trace[393007459] 'process raft request'  (duration: 125.667091ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:29.958059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.20235ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492483 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:23919 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:442 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:25:29.958237Z","caller":"traceutil/trace.go:171","msg":"trace[1837293195] linearizableReadLoop","detail":"{readStateIndex:24707; appliedIndex:24706; }","duration":"155.616636ms","start":"2026-05-18T01:25:29.802603Z","end":"2026-05-18T01:25:29.95822Z","steps":["trace[1837293195] 'read index received'  (duration: 2.12335ms)","trace[1837293195] 'applied index is now lower than readState.Index'  (duration: 153.491666ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:29.958353Z","caller":"traceutil/trace.go:171","msg":"trace[1262132365] transaction","detail":"{read_only:false; response_revision:23933; number_of_response:1; }","duration":"259.358603ms","start":"2026-05-18T01:25:29.698982Z","end":"2026-05-18T01:25:29.958341Z","steps":["trace[1262132365] 'process raft request'  (duration: 105.800286ms)","trace[1262132365] 'compare'  (duration: 152.798743ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:29.958636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.034724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:137"}
{"level":"info","ts":"2026-05-18T01:25:29.958793Z","caller":"traceutil/trace.go:171","msg":"trace[1177367047] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:23933; }","duration":"156.223198ms","start":"2026-05-18T01:25:29.802558Z","end":"2026-05-18T01:25:29.958781Z","steps":["trace[1177367047] 'agreement among raft nodes before linearized reading'  (duration: 155.972803ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:30.276428Z","caller":"traceutil/trace.go:171","msg":"trace[727524915] linearizableReadLoop","detail":"{readStateIndex:24708; appliedIndex:24707; }","duration":"296.875122ms","start":"2026-05-18T01:25:29.979525Z","end":"2026-05-18T01:25:30.2764Z","steps":["trace[727524915] 'read index received'  (duration: 296.656718ms)","trace[727524915] 'applied index is now lower than readState.Index'  (duration: 217.214µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:30.276552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:29.960284Z","time spent":"316.264977ms","remote":"127.0.0.1:50088","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-05-18T01:25:30.276965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.426342ms","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-05-18T01:25:30.277006Z","caller":"traceutil/trace.go:171","msg":"trace[1486005308] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23933; }","duration":"297.507043ms","start":"2026-05-18T01:25:29.979483Z","end":"2026-05-18T01:25:30.27699Z","steps":["trace[1486005308] 'agreement among raft nodes before linearized reading'  (duration: 297.352301ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:30.277183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.538222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:30.277221Z","caller":"traceutil/trace.go:171","msg":"trace[1485769174] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:23933; }","duration":"245.600693ms","start":"2026-05-18T01:25:30.031602Z","end":"2026-05-18T01:25:30.277203Z","steps":["trace[1485769174] 'agreement among raft nodes before linearized reading'  (duration: 245.545542ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:30.277592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.764702ms","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-05-18T01:25:30.277621Z","caller":"traceutil/trace.go:171","msg":"trace[123720290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23933; }","duration":"143.826143ms","start":"2026-05-18T01:25:30.133786Z","end":"2026-05-18T01:25:30.277613Z","steps":["trace[123720290] 'agreement among raft nodes before linearized reading'  (duration: 143.742931ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:30.541457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.837578ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215492491 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:23921 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:25:30.541663Z","caller":"traceutil/trace.go:171","msg":"trace[264818169] linearizableReadLoop","detail":"{readStateIndex:24710; appliedIndex:24708; }","duration":"259.66137ms","start":"2026-05-18T01:25:30.281985Z","end":"2026-05-18T01:25:30.541647Z","steps":["trace[264818169] 'read index received'  (duration: 17.063262ms)","trace[264818169] 'applied index is now lower than readState.Index'  (duration: 242.596748ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:30.541691Z","caller":"traceutil/trace.go:171","msg":"trace[1312884271] transaction","detail":"{read_only:false; response_revision:23936; number_of_response:1; }","duration":"259.424955ms","start":"2026-05-18T01:25:30.282238Z","end":"2026-05-18T01:25:30.541663Z","steps":["trace[1312884271] 'process raft request'  (duration: 259.314523ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:30.541751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.762562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:30.541792Z","caller":"traceutil/trace.go:171","msg":"trace[779188825] transaction","detail":"{read_only:false; response_revision:23935; number_of_response:1; }","duration":"259.885334ms","start":"2026-05-18T01:25:30.281887Z","end":"2026-05-18T01:25:30.541773Z","steps":["trace[779188825] 'process raft request'  (duration: 153.64246ms)","trace[779188825] 'compare'  (duration: 105.140393ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:30.541778Z","caller":"traceutil/trace.go:171","msg":"trace[1664223897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23936; }","duration":"259.822053ms","start":"2026-05-18T01:25:30.281947Z","end":"2026-05-18T01:25:30.54177Z","steps":["trace[1664223897] 'agreement among raft nodes before linearized reading'  (duration: 259.768352ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:30.604853Z","caller":"traceutil/trace.go:171","msg":"trace[1769460136] transaction","detail":"{read_only:false; response_revision:23937; number_of_response:1; }","duration":"179.403155ms","start":"2026-05-18T01:25:30.425413Z","end":"2026-05-18T01:25:30.604816Z","steps":["trace[1769460136] 'process raft request'  (duration: 179.191171ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:30.604872Z","caller":"traceutil/trace.go:171","msg":"trace[977247453] transaction","detail":"{read_only:false; response_revision:23938; number_of_response:1; }","duration":"179.185501ms","start":"2026-05-18T01:25:30.42566Z","end":"2026-05-18T01:25:30.604845Z","steps":["trace[977247453] 'process raft request'  (duration: 179.072079ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:30.605143Z","caller":"traceutil/trace.go:171","msg":"trace[331882405] transaction","detail":"{read_only:false; response_revision:23940; number_of_response:1; }","duration":"179.335404ms","start":"2026-05-18T01:25:30.42578Z","end":"2026-05-18T01:25:30.605115Z","steps":["trace[331882405] 'process raft request'  (duration: 179.024068ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:30.60527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.985665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"}
{"level":"info","ts":"2026-05-18T01:25:30.605368Z","caller":"traceutil/trace.go:171","msg":"trace[2065184842] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:23940; }","duration":"304.134098ms","start":"2026-05-18T01:25:30.301218Z","end":"2026-05-18T01:25:30.605352Z","steps":["trace[2065184842] 'agreement among raft nodes before linearized reading'  (duration: 303.812382ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:30.60543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.330297ms","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-05-18T01:25:30.605437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:30.3011Z","time spent":"304.323011ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "}
{"level":"info","ts":"2026-05-18T01:25:30.605466Z","caller":"traceutil/trace.go:171","msg":"trace[1476794970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23940; }","duration":"167.418768ms","start":"2026-05-18T01:25:30.438038Z","end":"2026-05-18T01:25:30.605457Z","steps":["trace[1476794970] 'agreement among raft nodes before linearized reading'  (duration: 167.202204ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:30.60592Z","caller":"traceutil/trace.go:171","msg":"trace[1433578056] transaction","detail":"{read_only:false; response_revision:23939; number_of_response:1; }","duration":"179.246172ms","start":"2026-05-18T01:25:30.425661Z","end":"2026-05-18T01:25:30.604907Z","steps":["trace[1433578056] 'process raft request'  (duration: 179.112019ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:38.681462Z","caller":"traceutil/trace.go:171","msg":"trace[241242671] linearizableReadLoop","detail":"{readStateIndex:24828; appliedIndex:24827; }","duration":"108.052878ms","start":"2026-05-18T01:25:38.573387Z","end":"2026-05-18T01:25:38.68144Z","steps":["trace[241242671] 'read index received'  (duration: 107.912985ms)","trace[241242671] 'applied index is now lower than readState.Index'  (duration: 138.763µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:38.681591Z","caller":"traceutil/trace.go:171","msg":"trace[816062722] transaction","detail":"{read_only:false; response_revision:24051; number_of_response:1; }","duration":"152.519007ms","start":"2026-05-18T01:25:38.529033Z","end":"2026-05-18T01:25:38.681552Z","steps":["trace[816062722] 'process raft request'  (duration: 152.186841ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:38.681685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.291913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-18T01:25:38.681745Z","caller":"traceutil/trace.go:171","msg":"trace[331592798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24051; }","duration":"108.406614ms","start":"2026-05-18T01:25:38.573322Z","end":"2026-05-18T01:25:38.681729Z","steps":["trace[331592798] 'agreement among raft nodes before linearized reading'  (duration: 108.240931ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:38.910429Z","caller":"traceutil/trace.go:171","msg":"trace[799127675] transaction","detail":"{read_only:false; response_revision:24052; number_of_response:1; }","duration":"278.500645ms","start":"2026-05-18T01:25:38.631904Z","end":"2026-05-18T01:25:38.910405Z","steps":["trace[799127675] 'process raft request'  (duration: 277.851453ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:38.91052Z","caller":"traceutil/trace.go:171","msg":"trace[2089344289] linearizableReadLoop","detail":"{readStateIndex:24832; appliedIndex:24828; }","duration":"228.831398ms","start":"2026-05-18T01:25:38.681635Z","end":"2026-05-18T01:25:38.910467Z","steps":["trace[2089344289] 'read index received'  (duration: 228.612134ms)","trace[2089344289] 'applied index is now lower than readState.Index'  (duration: 217.994µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:38.910686Z","caller":"traceutil/trace.go:171","msg":"trace[1795960120] transaction","detail":"{read_only:false; response_revision:24054; number_of_response:1; }","duration":"274.546931ms","start":"2026-05-18T01:25:38.636125Z","end":"2026-05-18T01:25:38.910671Z","steps":["trace[1795960120] 'process raft request'  (duration: 274.274446ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:38.910733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.808137ms","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-05-18T01:25:38.910764Z","caller":"traceutil/trace.go:171","msg":"trace[932361511] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24055; }","duration":"334.885688ms","start":"2026-05-18T01:25:38.575867Z","end":"2026-05-18T01:25:38.910753Z","steps":["trace[932361511] 'agreement among raft nodes before linearized reading'  (duration: 334.713445ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:38.910791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:38.575853Z","time spent":"334.93176ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-05-18T01:25:38.910848Z","caller":"traceutil/trace.go:171","msg":"trace[1187640650] transaction","detail":"{read_only:false; response_revision:24053; number_of_response:1; }","duration":"274.807356ms","start":"2026-05-18T01:25:38.63603Z","end":"2026-05-18T01:25:38.910837Z","steps":["trace[1187640650] 'process raft request'  (duration: 274.276616ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:38.91098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.139046ms","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-05-18T01:25:38.911069Z","caller":"traceutil/trace.go:171","msg":"trace[552384312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24055; }","duration":"263.249598ms","start":"2026-05-18T01:25:38.647795Z","end":"2026-05-18T01:25:38.911045Z","steps":["trace[552384312] 'agreement among raft nodes before linearized reading'  (duration: 262.949212ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:38.911215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.429511ms","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-05-18T01:25:38.911451Z","caller":"traceutil/trace.go:171","msg":"trace[495222921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24055; }","duration":"263.700806ms","start":"2026-05-18T01:25:38.647733Z","end":"2026-05-18T01:25:38.911434Z","steps":["trace[495222921] 'agreement among raft nodes before linearized reading'  (duration: 263.294008ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:38.911793Z","caller":"traceutil/trace.go:171","msg":"trace[364849570] transaction","detail":"{read_only:false; response_revision:24055; number_of_response:1; }","duration":"274.5062ms","start":"2026-05-18T01:25:38.636333Z","end":"2026-05-18T01:25:38.910839Z","steps":["trace[364849570] 'process raft request'  (duration: 274.097002ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:39.041295Z","caller":"traceutil/trace.go:171","msg":"trace[1328243306] transaction","detail":"{read_only:false; response_revision:24057; number_of_response:1; }","duration":"147.269639ms","start":"2026-05-18T01:25:38.894004Z","end":"2026-05-18T01:25:39.041273Z","steps":["trace[1328243306] 'process raft request'  (duration: 147.226368ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:39.041469Z","caller":"traceutil/trace.go:171","msg":"trace[132436097] transaction","detail":"{read_only:false; response_revision:24056; number_of_response:1; }","duration":"356.031578ms","start":"2026-05-18T01:25:38.685413Z","end":"2026-05-18T01:25:39.041445Z","steps":["trace[132436097] 'process raft request'  (duration: 334.534962ms)","trace[132436097] 'compare'  (duration: 21.18205ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:39.041608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:25:38.685395Z","time spent":"356.14926ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:23979 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-05-18T01:25:39.215011Z","caller":"traceutil/trace.go:171","msg":"trace[1864916810] linearizableReadLoop","detail":"{readStateIndex:24837; appliedIndex:24834; }","duration":"185.002371ms","start":"2026-05-18T01:25:39.02998Z","end":"2026-05-18T01:25:39.214982Z","steps":["trace[1864916810] 'read index received'  (duration: 150.524399ms)","trace[1864916810] 'applied index is now lower than readState.Index'  (duration: 34.476532ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:39.215161Z","caller":"traceutil/trace.go:171","msg":"trace[177590995] transaction","detail":"{read_only:false; response_revision:24058; number_of_response:1; }","duration":"299.209956ms","start":"2026-05-18T01:25:38.915924Z","end":"2026-05-18T01:25:39.215134Z","steps":["trace[177590995] 'process raft request'  (duration: 264.578161ms)","trace[177590995] 'compare'  (duration: 33.81264ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:39.215212Z","caller":"traceutil/trace.go:171","msg":"trace[1753754067] transaction","detail":"{read_only:false; response_revision:24059; number_of_response:1; }","duration":"299.215016ms","start":"2026-05-18T01:25:38.915956Z","end":"2026-05-18T01:25:39.215172Z","steps":["trace[1753754067] 'process raft request'  (duration: 298.86764ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:39.21541Z","caller":"traceutil/trace.go:171","msg":"trace[1986698040] transaction","detail":"{read_only:false; response_revision:24060; number_of_response:1; }","duration":"299.126124ms","start":"2026-05-18T01:25:38.916263Z","end":"2026-05-18T01:25:39.21539Z","steps":["trace[1986698040] 'process raft request'  (duration: 298.655926ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:39.215646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.643853ms","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-05-18T01:25:39.215733Z","caller":"traceutil/trace.go:171","msg":"trace[1140186890] 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:24060; }","duration":"185.757035ms","start":"2026-05-18T01:25:39.02994Z","end":"2026-05-18T01:25:39.215697Z","steps":["trace[1140186890] 'agreement among raft nodes before linearized reading'  (duration: 185.634093ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:39.216149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.522631ms","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-05-18T01:25:39.216211Z","caller":"traceutil/trace.go:171","msg":"trace[1281501754] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24060; }","duration":"132.647932ms","start":"2026-05-18T01:25:39.083548Z","end":"2026-05-18T01:25:39.216196Z","steps":["trace[1281501754] 'agreement among raft nodes before linearized reading'  (duration: 132.430858ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:40.01244Z","caller":"traceutil/trace.go:171","msg":"trace[1790531651] linearizableReadLoop","detail":"{readStateIndex:24841; appliedIndex:24840; }","duration":"144.621549ms","start":"2026-05-18T01:25:39.867787Z","end":"2026-05-18T01:25:40.012409Z","steps":["trace[1790531651] 'read index received'  (duration: 144.529847ms)","trace[1790531651] 'applied index is now lower than readState.Index'  (duration: 89.852µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:25:40.012598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.791163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:25:40.012653Z","caller":"traceutil/trace.go:171","msg":"trace[1065240372] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24063; }","duration":"144.888864ms","start":"2026-05-18T01:25:39.867749Z","end":"2026-05-18T01:25:40.012638Z","steps":["trace[1065240372] 'agreement among raft nodes before linearized reading'  (duration: 144.775741ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:40.137966Z","caller":"traceutil/trace.go:171","msg":"trace[1153605339] transaction","detail":"{read_only:false; response_revision:24064; number_of_response:1; }","duration":"147.32558ms","start":"2026-05-18T01:25:39.990615Z","end":"2026-05-18T01:25:40.13794Z","steps":["trace[1153605339] 'process raft request'  (duration: 141.896897ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:40.138634Z","caller":"traceutil/trace.go:171","msg":"trace[919339784] linearizableReadLoop","detail":"{readStateIndex:24843; appliedIndex:24841; }","duration":"122.347718ms","start":"2026-05-18T01:25:40.016259Z","end":"2026-05-18T01:25:40.138606Z","steps":["trace[919339784] 'read index received'  (duration: 116.207322ms)","trace[919339784] 'applied index is now lower than readState.Index'  (duration: 6.138476ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:40.138678Z","caller":"traceutil/trace.go:171","msg":"trace[1614048700] transaction","detail":"{read_only:false; response_revision:24065; number_of_response:1; }","duration":"125.080891ms","start":"2026-05-18T01:25:40.01358Z","end":"2026-05-18T01:25:40.138661Z","steps":["trace[1614048700] 'process raft request'  (duration: 124.284575ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:40.138867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.599383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-05-18T01:25:40.138911Z","caller":"traceutil/trace.go:171","msg":"trace[1046829693] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24065; }","duration":"122.698525ms","start":"2026-05-18T01:25:40.016202Z","end":"2026-05-18T01:25:40.1389Z","steps":["trace[1046829693] 'agreement among raft nodes before linearized reading'  (duration: 122.501462ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:40.430151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.311142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-712c3bcd-d8d4-4139-8a6c-c4ea13b8d6d7\" ","response":"range_response_count:1 size:3763"}
{"level":"info","ts":"2026-05-18T01:25:40.430251Z","caller":"traceutil/trace.go:171","msg":"trace[1579736745] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-712c3bcd-d8d4-4139-8a6c-c4ea13b8d6d7; range_end:; response_count:1; response_revision:24065; }","duration":"172.505536ms","start":"2026-05-18T01:25:40.257722Z","end":"2026-05-18T01:25:40.430228Z","steps":["trace[1579736745] 'range keys from in-memory index tree'  (duration: 172.076878ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:40.707113Z","caller":"traceutil/trace.go:171","msg":"trace[1473360476] transaction","detail":"{read_only:false; response_revision:24066; number_of_response:1; }","duration":"218.795399ms","start":"2026-05-18T01:25:40.488296Z","end":"2026-05-18T01:25:40.707091Z","steps":["trace[1473360476] 'process raft request'  (duration: 218.641206ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:43.058732Z","caller":"traceutil/trace.go:171","msg":"trace[1033912084] transaction","detail":"{read_only:false; response_revision:24082; number_of_response:1; }","duration":"135.944605ms","start":"2026-05-18T01:25:42.922766Z","end":"2026-05-18T01:25:43.058711Z","steps":["trace[1033912084] 'process raft request'  (duration: 135.696851ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:43.058902Z","caller":"traceutil/trace.go:171","msg":"trace[969355685] transaction","detail":"{read_only:false; response_revision:24083; number_of_response:1; }","duration":"136.078018ms","start":"2026-05-18T01:25:42.922776Z","end":"2026-05-18T01:25:43.058854Z","steps":["trace[969355685] 'process raft request'  (duration: 135.893865ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:43.071Z","caller":"traceutil/trace.go:171","msg":"trace[280968488] transaction","detail":"{read_only:false; response_revision:24084; number_of_response:1; }","duration":"145.792491ms","start":"2026-05-18T01:25:42.925193Z","end":"2026-05-18T01:25:43.070986Z","steps":["trace[280968488] 'process raft request'  (duration: 145.695739ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:43.071259Z","caller":"traceutil/trace.go:171","msg":"trace[1560797436] linearizableReadLoop","detail":"{readStateIndex:24863; appliedIndex:24860; }","duration":"120.678856ms","start":"2026-05-18T01:25:42.950558Z","end":"2026-05-18T01:25:43.071237Z","steps":["trace[1560797436] 'read index received'  (duration: 108.094889ms)","trace[1560797436] 'applied index is now lower than readState.Index'  (duration: 12.582887ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:25:43.07131Z","caller":"traceutil/trace.go:171","msg":"trace[590213830] transaction","detail":"{read_only:false; response_revision:24085; number_of_response:1; }","duration":"146.019224ms","start":"2026-05-18T01:25:42.925269Z","end":"2026-05-18T01:25:43.071289Z","steps":["trace[590213830] 'process raft request'  (duration: 145.694758ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:25:43.071486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.916051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"}
{"level":"info","ts":"2026-05-18T01:25:43.071515Z","caller":"traceutil/trace.go:171","msg":"trace[1729143196] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:24086; }","duration":"120.960662ms","start":"2026-05-18T01:25:42.950549Z","end":"2026-05-18T01:25:43.071509Z","steps":["trace[1729143196] 'agreement among raft nodes before linearized reading'  (duration: 120.809769ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:25:46.945944Z","caller":"traceutil/trace.go:171","msg":"trace[1397656110] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"167.473349ms","start":"2026-05-18T01:25:46.778443Z","end":"2026-05-18T01:25:46.945916Z","steps":["trace[1397656110] 'process raft request'  (duration: 167.303266ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:13.410254Z","caller":"traceutil/trace.go:171","msg":"trace[2020858468] transaction","detail":"{read_only:false; response_revision:24373; number_of_response:1; }","duration":"250.381775ms","start":"2026-05-18T01:26:13.159852Z","end":"2026-05-18T01:26:13.410234Z","steps":["trace[2020858468] 'process raft request'  (duration: 227.117065ms)","trace[2020858468] 'compare'  (duration: 22.854522ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:13.410427Z","caller":"traceutil/trace.go:171","msg":"trace[836813627] transaction","detail":"{read_only:false; response_revision:24376; number_of_response:1; }","duration":"231.291475ms","start":"2026-05-18T01:26:13.179117Z","end":"2026-05-18T01:26:13.410408Z","steps":["trace[836813627] 'process raft request'  (duration: 231.031249ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:13.410645Z","caller":"traceutil/trace.go:171","msg":"trace[656095479] transaction","detail":"{read_only:false; response_revision:24374; number_of_response:1; }","duration":"241.299924ms","start":"2026-05-18T01:26:13.169333Z","end":"2026-05-18T01:26:13.410633Z","steps":["trace[656095479] 'process raft request'  (duration: 240.725203ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:13.410721Z","caller":"traceutil/trace.go:171","msg":"trace[318321933] transaction","detail":"{read_only:false; response_revision:24375; number_of_response:1; }","duration":"239.080693ms","start":"2026-05-18T01:26:13.171635Z","end":"2026-05-18T01:26:13.410715Z","steps":["trace[318321933] 'process raft request'  (duration: 238.478191ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:13.594586Z","caller":"traceutil/trace.go:171","msg":"trace[528820120] transaction","detail":"{read_only:false; response_revision:24377; number_of_response:1; }","duration":"177.993986ms","start":"2026-05-18T01:26:13.41656Z","end":"2026-05-18T01:26:13.594554Z","steps":["trace[528820120] 'process raft request'  (duration: 137.438869ms)","trace[528820120] 'compare'  (duration: 40.280532ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:13.694121Z","caller":"traceutil/trace.go:171","msg":"trace[701456437] transaction","detail":"{read_only:false; response_revision:24378; number_of_response:1; }","duration":"277.152472ms","start":"2026-05-18T01:26:13.416948Z","end":"2026-05-18T01:26:13.6941Z","steps":["trace[701456437] 'process raft request'  (duration: 276.993599ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:13.694378Z","caller":"traceutil/trace.go:171","msg":"trace[441291397] transaction","detail":"{read_only:false; response_revision:24379; number_of_response:1; }","duration":"277.158681ms","start":"2026-05-18T01:26:13.417181Z","end":"2026-05-18T01:26:13.694339Z","steps":["trace[441291397] 'process raft request'  (duration: 276.888076ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:13.737939Z","caller":"traceutil/trace.go:171","msg":"trace[1760666672] linearizableReadLoop","detail":"{readStateIndex:25166; appliedIndex:25161; }","duration":"299.626277ms","start":"2026-05-18T01:26:13.438296Z","end":"2026-05-18T01:26:13.737922Z","steps":["trace[1760666672] 'read index received'  (duration: 115.773031ms)","trace[1760666672] 'applied index is now lower than readState.Index'  (duration: 183.852726ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:13.738006Z","caller":"traceutil/trace.go:171","msg":"trace[1924729850] transaction","detail":"{read_only:false; response_revision:24380; number_of_response:1; }","duration":"320.545902ms","start":"2026-05-18T01:26:13.417415Z","end":"2026-05-18T01:26:13.737961Z","steps":["trace[1924729850] 'process raft request'  (duration: 276.836246ms)","trace[1924729850] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 43.481911ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:13.738037Z","caller":"traceutil/trace.go:171","msg":"trace[1700719729] transaction","detail":"{read_only:false; response_revision:24381; number_of_response:1; }","duration":"320.559552ms","start":"2026-05-18T01:26:13.417432Z","end":"2026-05-18T01:26:13.737991Z","steps":["trace[1700719729] 'process raft request'  (duration: 320.44216ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:13.73813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.81135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-5f476489cc-wv6ls.18b083058a4ff6b5\" ","response":"range_response_count:1 size:1978"}
{"level":"info","ts":"2026-05-18T01:26:13.738158Z","caller":"traceutil/trace.go:171","msg":"trace[200929951] range","detail":"{range_begin:/registry/events/openstack/glance-api-5f476489cc-wv6ls.18b083058a4ff6b5; range_end:; response_count:1; response_revision:24381; }","duration":"299.857442ms","start":"2026-05-18T01:26:13.43829Z","end":"2026-05-18T01:26:13.738148Z","steps":["trace[200929951] 'agreement among raft nodes before linearized reading'  (duration: 299.729709ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:13.738172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:13.417406Z","time spent":"320.669944ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:24364 > 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-05-18T01:26:13.738218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.36318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"}
{"level":"warn","ts":"2026-05-18T01:26:13.738248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:13.417416Z","time spent":"320.718225ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:24362 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-05-18T01:26:13.738294Z","caller":"traceutil/trace.go:171","msg":"trace[1427385845] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:24381; }","duration":"291.485263ms","start":"2026-05-18T01:26:13.446787Z","end":"2026-05-18T01:26:13.738272Z","steps":["trace[1427385845] 'agreement among raft nodes before linearized reading'  (duration: 291.269198ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:13.738338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.75193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:13.738437Z","caller":"traceutil/trace.go:171","msg":"trace[194560502] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:24381; }","duration":"115.904963ms","start":"2026-05-18T01:26:13.62251Z","end":"2026-05-18T01:26:13.738415Z","steps":["trace[194560502] 'agreement among raft nodes before linearized reading'  (duration: 115.74579ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:13.985591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.558693ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215493566 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/openstack/glance-api-5f476489cc-wv6ls.18b083058a4ff6b5\" mod_revision:16200 > success:<request_put:<key:\"/registry/events/openstack/glance-api-5f476489cc-wv6ls.18b083058a4ff6b5\" value_size:1874 lease:4487166569360716809 >> failure:<request_range:<key:\"/registry/events/openstack/glance-api-5f476489cc-wv6ls.18b083058a4ff6b5\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:26:13.985765Z","caller":"traceutil/trace.go:171","msg":"trace[840778032] transaction","detail":"{read_only:false; response_revision:24382; number_of_response:1; }","duration":"242.888564ms","start":"2026-05-18T01:26:13.742846Z","end":"2026-05-18T01:26:13.985734Z","steps":["trace[840778032] 'process raft request'  (duration: 134.060356ms)","trace[840778032] 'compare'  (duration: 107.490492ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:14.056229Z","caller":"traceutil/trace.go:171","msg":"trace[1375947043] transaction","detail":"{read_only:false; response_revision:24383; number_of_response:1; }","duration":"313.156453ms","start":"2026-05-18T01:26:13.743054Z","end":"2026-05-18T01:26:14.056211Z","steps":["trace[1375947043] 'process raft request'  (duration: 313.00139ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:14.056374Z","caller":"traceutil/trace.go:171","msg":"trace[2012173130] linearizableReadLoop","detail":"{readStateIndex:25168; appliedIndex:25166; }","duration":"310.997602ms","start":"2026-05-18T01:26:13.745365Z","end":"2026-05-18T01:26:14.056363Z","steps":["trace[2012173130] 'read index received'  (duration: 131.554378ms)","trace[2012173130] 'applied index is now lower than readState.Index'  (duration: 179.441994ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:14.056423Z","caller":"traceutil/trace.go:171","msg":"trace[1406177590] transaction","detail":"{read_only:false; response_revision:24384; number_of_response:1; }","duration":"233.795241ms","start":"2026-05-18T01:26:13.822621Z","end":"2026-05-18T01:26:14.056416Z","steps":["trace[1406177590] 'process raft request'  (duration: 233.552306ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:14.056668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.299997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-00455c86-350d-4f9e-9c83-b35a55914262\" ","response":"range_response_count:1 size:1451"}
{"level":"info","ts":"2026-05-18T01:26:14.056694Z","caller":"traceutil/trace.go:171","msg":"trace[1529500748] range","detail":"{range_begin:/registry/persistentvolumes/pvc-00455c86-350d-4f9e-9c83-b35a55914262; range_end:; response_count:1; response_revision:24384; }","duration":"311.356908ms","start":"2026-05-18T01:26:13.74533Z","end":"2026-05-18T01:26:14.056687Z","steps":["trace[1529500748] 'agreement among raft nodes before linearized reading'  (duration: 311.251336ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:14.05671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:13.745316Z","time spent":"311.390279ms","remote":"127.0.0.1:50228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1475,"request content":"key:\"/registry/persistentvolumes/pvc-00455c86-350d-4f9e-9c83-b35a55914262\" "}
{"level":"warn","ts":"2026-05-18T01:26:14.056863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:13.743026Z","time spent":"313.277235ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24367 > 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-05-18T01:26:14.248704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.103063ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215493569 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24369 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:26:14.24878Z","caller":"traceutil/trace.go:171","msg":"trace[184682114] linearizableReadLoop","detail":"{readStateIndex:25170; appliedIndex:25169; }","duration":"192.372739ms","start":"2026-05-18T01:26:14.056395Z","end":"2026-05-18T01:26:14.248768Z","steps":["trace[184682114] 'read index received'  (duration: 72.859448ms)","trace[184682114] 'applied index is now lower than readState.Index'  (duration: 119.512551ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:14.248843Z","caller":"traceutil/trace.go:171","msg":"trace[649479812] transaction","detail":"{read_only:false; response_revision:24385; number_of_response:1; }","duration":"348.242137ms","start":"2026-05-18T01:26:13.900593Z","end":"2026-05-18T01:26:14.248835Z","steps":["trace[649479812] 'process raft request'  (duration: 228.774927ms)","trace[649479812] 'compare'  (duration: 118.9159ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:14.248886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:13.90057Z","time spent":"348.294968ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:24369 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"warn","ts":"2026-05-18T01:26:14.248964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.637422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:14.248993Z","caller":"traceutil/trace.go:171","msg":"trace[525266506] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24385; }","duration":"386.703483ms","start":"2026-05-18T01:26:13.862283Z","end":"2026-05-18T01:26:14.248986Z","steps":["trace[525266506] 'agreement among raft nodes before linearized reading'  (duration: 386.647602ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:14.249013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:13.86226Z","time spent":"386.747814ms","remote":"127.0.0.1:47770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T01:26:14.249026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.505411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:14.249096Z","caller":"traceutil/trace.go:171","msg":"trace[1780863727] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24385; }","duration":"476.591574ms","start":"2026-05-18T01:26:13.772486Z","end":"2026-05-18T01:26:14.249078Z","steps":["trace[1780863727] 'agreement among raft nodes before linearized reading'  (duration: 476.455691ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:14.249149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:13.772466Z","time spent":"476.666275ms","remote":"127.0.0.1:47630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T01:26:14.652189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.011436ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215493574 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:24371 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:26:14.652709Z","caller":"traceutil/trace.go:171","msg":"trace[1684626952] transaction","detail":"{read_only:false; response_revision:24386; number_of_response:1; }","duration":"391.248699ms","start":"2026-05-18T01:26:14.261424Z","end":"2026-05-18T01:26:14.652672Z","steps":["trace[1684626952] 'process raft request'  (duration: 128.669953ms)","trace[1684626952] 'compare'  (duration: 261.727151ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:14.652817Z","caller":"traceutil/trace.go:171","msg":"trace[1979892131] transaction","detail":"{read_only:false; response_revision:24387; number_of_response:1; }","duration":"144.586285ms","start":"2026-05-18T01:26:14.508211Z","end":"2026-05-18T01:26:14.652798Z","steps":["trace[1979892131] 'process raft request'  (duration: 144.479253ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:14.652824Z","caller":"traceutil/trace.go:171","msg":"trace[1933587047] linearizableReadLoop","detail":"{readStateIndex:25171; appliedIndex:25170; }","duration":"379.12053ms","start":"2026-05-18T01:26:14.27368Z","end":"2026-05-18T01:26:14.652801Z","steps":["trace[1933587047] 'read index received'  (duration: 116.34809ms)","trace[1933587047] 'applied index is now lower than readState.Index'  (duration: 262.77042ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:14.652878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:14.261409Z","time spent":"391.380593ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:24371 > 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-05-18T01:26:14.652971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.285723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:14.653005Z","caller":"traceutil/trace.go:171","msg":"trace[1453599845] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24387; }","duration":"379.410205ms","start":"2026-05-18T01:26:14.273584Z","end":"2026-05-18T01:26:14.652994Z","steps":["trace[1453599845] 'agreement among raft nodes before linearized reading'  (duration: 379.326274ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:14.653032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:14.273559Z","time spent":"379.466756ms","remote":"127.0.0.1:51490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T01:26:14.653123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.760622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:14.653166Z","caller":"traceutil/trace.go:171","msg":"trace[1860322211] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:24387; }","duration":"361.858343ms","start":"2026-05-18T01:26:14.291295Z","end":"2026-05-18T01:26:14.653154Z","steps":["trace[1860322211] 'agreement among raft nodes before linearized reading'  (duration: 361.790312ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:14.653204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:14.291267Z","time spent":"361.927334ms","remote":"127.0.0.1:50990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T01:26:14.653298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.250474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:14.653326Z","caller":"traceutil/trace.go:171","msg":"trace[456221176] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24387; }","duration":"313.310375ms","start":"2026-05-18T01:26:14.340008Z","end":"2026-05-18T01:26:14.653319Z","steps":["trace[456221176] 'agreement among raft nodes before linearized reading'  (duration: 313.265285ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:14.653348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:14.339993Z","time spent":"313.349596ms","remote":"127.0.0.1:47628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T01:26:14.653364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.85128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"}
{"level":"info","ts":"2026-05-18T01:26:14.653413Z","caller":"traceutil/trace.go:171","msg":"trace[508011644] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24387; }","duration":"180.901011ms","start":"2026-05-18T01:26:14.4725Z","end":"2026-05-18T01:26:14.653401Z","steps":["trace[508011644] 'agreement among raft nodes before linearized reading'  (duration: 180.747828ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:15.332728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.753543ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215493581 > lease_revoke:<id:3e459e3886c0f672>","response":"size:30"}
{"level":"info","ts":"2026-05-18T01:26:15.3328Z","caller":"traceutil/trace.go:171","msg":"trace[810808994] linearizableReadLoop","detail":"{readStateIndex:25173; appliedIndex:25172; }","duration":"275.100842ms","start":"2026-05-18T01:26:15.057685Z","end":"2026-05-18T01:26:15.332786Z","steps":["trace[810808994] 'read index received'  (duration: 116.256168ms)","trace[810808994] 'applied index is now lower than readState.Index'  (duration: 158.843774ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:15.333057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.356317ms","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-05-18T01:26:15.333079Z","caller":"traceutil/trace.go:171","msg":"trace[12080899] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:24387; }","duration":"275.418768ms","start":"2026-05-18T01:26:15.057654Z","end":"2026-05-18T01:26:15.333072Z","steps":["trace[12080899] 'agreement among raft nodes before linearized reading'  (duration: 275.347627ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:15.596542Z","caller":"traceutil/trace.go:171","msg":"trace[1596875835] transaction","detail":"{read_only:false; response_revision:24388; number_of_response:1; }","duration":"344.265751ms","start":"2026-05-18T01:26:15.252256Z","end":"2026-05-18T01:26:15.596522Z","steps":["trace[1596875835] 'process raft request'  (duration: 344.124408ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:15.596682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:15.252236Z","time spent":"344.374512ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:24372 > 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\" > >"}
{"level":"info","ts":"2026-05-18T01:26:15.597228Z","caller":"traceutil/trace.go:171","msg":"trace[1934781646] linearizableReadLoop","detail":"{readStateIndex:25174; appliedIndex:25174; }","duration":"264.40286ms","start":"2026-05-18T01:26:15.332814Z","end":"2026-05-18T01:26:15.597217Z","steps":["trace[1934781646] 'read index received'  (duration: 264.394279ms)","trace[1934781646] 'applied index is now lower than readState.Index'  (duration: 7.641µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:15.597295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.405874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:15.597493Z","caller":"traceutil/trace.go:171","msg":"trace[192429473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24388; }","duration":"417.467915ms","start":"2026-05-18T01:26:15.179848Z","end":"2026-05-18T01:26:15.597316Z","steps":["trace[192429473] 'agreement among raft nodes before linearized reading'  (duration: 417.412894ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:15.597513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:15.179832Z","time spent":"417.678089ms","remote":"127.0.0.1:50058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-05-18T01:26:15.645912Z","caller":"traceutil/trace.go:171","msg":"trace[224070041] transaction","detail":"{read_only:false; response_revision:24392; number_of_response:1; }","duration":"228.368559ms","start":"2026-05-18T01:26:15.417505Z","end":"2026-05-18T01:26:15.645874Z","steps":["trace[224070041] 'process raft request'  (duration: 228.228846ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:15.646231Z","caller":"traceutil/trace.go:171","msg":"trace[298269591] transaction","detail":"{read_only:false; response_revision:24389; number_of_response:1; }","duration":"230.467279ms","start":"2026-05-18T01:26:15.415753Z","end":"2026-05-18T01:26:15.64622Z","steps":["trace[298269591] 'process raft request'  (duration: 229.044712ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:15.646461Z","caller":"traceutil/trace.go:171","msg":"trace[110230710] transaction","detail":"{read_only:false; response_revision:24390; number_of_response:1; }","duration":"230.54271ms","start":"2026-05-18T01:26:15.415905Z","end":"2026-05-18T01:26:15.646448Z","steps":["trace[110230710] 'process raft request'  (duration: 229.733784ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:15.646576Z","caller":"traceutil/trace.go:171","msg":"trace[1669034827] transaction","detail":"{read_only:false; response_revision:24391; number_of_response:1; }","duration":"229.410538ms","start":"2026-05-18T01:26:15.417157Z","end":"2026-05-18T01:26:15.646567Z","steps":["trace[1669034827] 'process raft request'  (duration: 228.539251ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:15.916162Z","caller":"traceutil/trace.go:171","msg":"trace[1083308614] linearizableReadLoop","detail":"{readStateIndex:25179; appliedIndex:25178; }","duration":"218.232367ms","start":"2026-05-18T01:26:15.697904Z","end":"2026-05-18T01:26:15.916136Z","steps":["trace[1083308614] 'read index received'  (duration: 195.554268ms)","trace[1083308614] 'applied index is now lower than readState.Index'  (duration: 22.676979ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:15.916265Z","caller":"traceutil/trace.go:171","msg":"trace[247852225] transaction","detail":"{read_only:false; response_revision:24393; number_of_response:1; }","duration":"266.386897ms","start":"2026-05-18T01:26:15.649852Z","end":"2026-05-18T01:26:15.916239Z","steps":["trace[247852225] 'process raft request'  (duration: 243.602696ms)","trace[247852225] 'compare'  (duration: 22.563457ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:15.916447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.520282ms","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-05-18T01:26:15.916509Z","caller":"traceutil/trace.go:171","msg":"trace[313536994] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24393; }","duration":"218.629325ms","start":"2026-05-18T01:26:15.697859Z","end":"2026-05-18T01:26:15.916488Z","steps":["trace[313536994] 'agreement among raft nodes before linearized reading'  (duration: 218.433531ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:15.916506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.156576ms","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-05-18T01:26:15.916664Z","caller":"traceutil/trace.go:171","msg":"trace[167936903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24393; }","duration":"218.34255ms","start":"2026-05-18T01:26:15.698306Z","end":"2026-05-18T01:26:15.916649Z","steps":["trace[167936903] 'agreement among raft nodes before linearized reading'  (duration: 218.082095ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:15.916755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.167344ms","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-05-18T01:26:15.916789Z","caller":"traceutil/trace.go:171","msg":"trace[1613814233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24393; }","duration":"174.203844ms","start":"2026-05-18T01:26:15.742572Z","end":"2026-05-18T01:26:15.916776Z","steps":["trace[1613814233] 'agreement among raft nodes before linearized reading'  (duration: 174.141453ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:15.916916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.356567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-18T01:26:15.916933Z","caller":"traceutil/trace.go:171","msg":"trace[783630017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24393; }","duration":"174.432938ms","start":"2026-05-18T01:26:15.742495Z","end":"2026-05-18T01:26:15.916928Z","steps":["trace[783630017] 'agreement among raft nodes before linearized reading'  (duration: 174.347416ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:16.179369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.730568ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215493594 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:24378 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:26:16.1796Z","caller":"traceutil/trace.go:171","msg":"trace[47700681] transaction","detail":"{read_only:false; response_revision:24397; number_of_response:1; }","duration":"255.271808ms","start":"2026-05-18T01:26:15.924311Z","end":"2026-05-18T01:26:16.179583Z","steps":["trace[47700681] 'process raft request'  (duration: 255.235087ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:16.179711Z","caller":"traceutil/trace.go:171","msg":"trace[1713170401] linearizableReadLoop","detail":"{readStateIndex:25180; appliedIndex:25179; }","duration":"258.649702ms","start":"2026-05-18T01:26:15.921043Z","end":"2026-05-18T01:26:16.179693Z","steps":["trace[1713170401] 'read index received'  (duration: 123.527366ms)","trace[1713170401] 'applied index is now lower than readState.Index'  (duration: 135.120706ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:16.179755Z","caller":"traceutil/trace.go:171","msg":"trace[1526441806] transaction","detail":"{read_only:false; response_revision:24395; number_of_response:1; }","duration":"258.008169ms","start":"2026-05-18T01:26:15.921718Z","end":"2026-05-18T01:26:16.179726Z","steps":["trace[1526441806] 'process raft request'  (duration: 257.749274ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:16.179905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.851355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-05-18T01:26:16.1798Z","caller":"traceutil/trace.go:171","msg":"trace[1654359172] transaction","detail":"{read_only:false; response_revision:24394; number_of_response:1; }","duration":"259.051859ms","start":"2026-05-18T01:26:15.92074Z","end":"2026-05-18T01:26:16.179792Z","steps":["trace[1654359172] 'process raft request'  (duration: 123.823931ms)","trace[1654359172] 'compare'  (duration: 134.563715ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:16.179932Z","caller":"traceutil/trace.go:171","msg":"trace[373941963] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24397; }","duration":"258.918756ms","start":"2026-05-18T01:26:15.921008Z","end":"2026-05-18T01:26:16.179927Z","steps":["trace[373941963] 'agreement among raft nodes before linearized reading'  (duration: 258.804404ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:16.179772Z","caller":"traceutil/trace.go:171","msg":"trace[1323551365] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"256.221226ms","start":"2026-05-18T01:26:15.923529Z","end":"2026-05-18T01:26:16.17975Z","steps":["trace[1323551365] 'process raft request'  (duration: 255.987791ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:16.179977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.067782ms","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-05-18T01:26:16.180016Z","caller":"traceutil/trace.go:171","msg":"trace[959729131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24397; }","duration":"119.119072ms","start":"2026-05-18T01:26:16.060885Z","end":"2026-05-18T01:26:16.180004Z","steps":["trace[959729131] 'agreement among raft nodes before linearized reading'  (duration: 118.949519ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:16.450046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.525098ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215493601 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:24383 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:26:16.45014Z","caller":"traceutil/trace.go:171","msg":"trace[1918077978] linearizableReadLoop","detail":"{readStateIndex:25184; appliedIndex:25183; }","duration":"256.324528ms","start":"2026-05-18T01:26:16.193801Z","end":"2026-05-18T01:26:16.450126Z","steps":["trace[1918077978] 'read index received'  (duration: 87.654287ms)","trace[1918077978] 'applied index is now lower than readState.Index'  (duration: 168.669191ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:16.450206Z","caller":"traceutil/trace.go:171","msg":"trace[435953257] transaction","detail":"{read_only:false; response_revision:24398; number_of_response:1; }","duration":"266.710985ms","start":"2026-05-18T01:26:16.183484Z","end":"2026-05-18T01:26:16.450195Z","steps":["trace[435953257] 'process raft request'  (duration: 97.966573ms)","trace[435953257] 'compare'  (duration: 168.187871ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:16.450259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.44976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:16.450316Z","caller":"traceutil/trace.go:171","msg":"trace[749151440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24398; }","duration":"256.533352ms","start":"2026-05-18T01:26:16.193768Z","end":"2026-05-18T01:26:16.450302Z","steps":["trace[749151440] 'agreement among raft nodes before linearized reading'  (duration: 256.44865ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:16.486303Z","caller":"traceutil/trace.go:171","msg":"trace[677360510] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"230.738893ms","start":"2026-05-18T01:26:16.255538Z","end":"2026-05-18T01:26:16.486277Z","steps":["trace[677360510] 'process raft request'  (duration: 230.55039ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:16.486369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.850999ms","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-05-18T01:26:16.486438Z","caller":"traceutil/trace.go:171","msg":"trace[430489834] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24399; }","duration":"246.149345ms","start":"2026-05-18T01:26:16.240272Z","end":"2026-05-18T01:26:16.486421Z","steps":["trace[430489834] 'agreement among raft nodes before linearized reading'  (duration: 245.971191ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:16.486531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.288861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:16.486603Z","caller":"traceutil/trace.go:171","msg":"trace[110027685] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24399; }","duration":"133.469874ms","start":"2026-05-18T01:26:16.353115Z","end":"2026-05-18T01:26:16.486585Z","steps":["trace[110027685] 'agreement among raft nodes before linearized reading'  (duration: 133.325381ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:26.806211Z","caller":"traceutil/trace.go:171","msg":"trace[63280642] linearizableReadLoop","detail":"{readStateIndex:25262; appliedIndex:25261; }","duration":"104.214391ms","start":"2026-05-18T01:26:26.701959Z","end":"2026-05-18T01:26:26.806174Z","steps":["trace[63280642] 'read index received'  (duration: 103.900875ms)","trace[63280642] 'applied index is now lower than readState.Index'  (duration: 312.426µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:26.806235Z","caller":"traceutil/trace.go:171","msg":"trace[766830325] transaction","detail":"{read_only:false; response_revision:24474; number_of_response:1; }","duration":"115.213458ms","start":"2026-05-18T01:26:26.69099Z","end":"2026-05-18T01:26:26.806203Z","steps":["trace[766830325] 'process raft request'  (duration: 114.965704ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:26.806448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.470625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"}
{"level":"info","ts":"2026-05-18T01:26:26.806568Z","caller":"traceutil/trace.go:171","msg":"trace[1017769780] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24474; }","duration":"104.64079ms","start":"2026-05-18T01:26:26.701907Z","end":"2026-05-18T01:26:26.806548Z","steps":["trace[1017769780] 'agreement among raft nodes before linearized reading'  (duration: 104.375464ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:27.850861Z","caller":"traceutil/trace.go:171","msg":"trace[358746803] transaction","detail":"{read_only:false; response_revision:24477; number_of_response:1; }","duration":"147.746834ms","start":"2026-05-18T01:26:27.703084Z","end":"2026-05-18T01:26:27.850831Z","steps":["trace[358746803] 'process raft request'  (duration: 135.411021ms)","trace[358746803] 'compare'  (duration: 12.140969ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:27.996578Z","caller":"traceutil/trace.go:171","msg":"trace[1059449594] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"286.60042ms","start":"2026-05-18T01:26:27.709952Z","end":"2026-05-18T01:26:27.996552Z","steps":["trace[1059449594] 'process raft request'  (duration: 286.524218ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:27.996604Z","caller":"traceutil/trace.go:171","msg":"trace[1035407904] transaction","detail":"{read_only:false; response_revision:24478; number_of_response:1; }","duration":"286.62494ms","start":"2026-05-18T01:26:27.709947Z","end":"2026-05-18T01:26:27.996572Z","steps":["trace[1035407904] 'process raft request'  (duration: 286.372905ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:27.996695Z","caller":"traceutil/trace.go:171","msg":"trace[2143473920] transaction","detail":"{read_only:false; response_revision:24480; number_of_response:1; }","duration":"284.905678ms","start":"2026-05-18T01:26:27.711777Z","end":"2026-05-18T01:26:27.996683Z","steps":["trace[2143473920] 'process raft request'  (duration: 284.739475ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:27.996693Z","caller":"traceutil/trace.go:171","msg":"trace[1323740268] linearizableReadLoop","detail":"{readStateIndex:25268; appliedIndex:25265; }","duration":"141.128728ms","start":"2026-05-18T01:26:27.855525Z","end":"2026-05-18T01:26:27.996654Z","steps":["trace[1323740268] 'read index received'  (duration: 140.749711ms)","trace[1323740268] 'applied index is now lower than readState.Index'  (duration: 376.207µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:27.996815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.257891ms","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-05-18T01:26:27.996846Z","caller":"traceutil/trace.go:171","msg":"trace[173635802] 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:24480; }","duration":"141.349203ms","start":"2026-05-18T01:26:27.855489Z","end":"2026-05-18T01:26:27.996838Z","steps":["trace[173635802] 'agreement among raft nodes before linearized reading'  (duration: 141.268361ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:28.292213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.283076ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13710538606215493811 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:24467 > 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\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T01:26:28.292326Z","caller":"traceutil/trace.go:171","msg":"trace[8734638] linearizableReadLoop","detail":"{readStateIndex:25269; appliedIndex:25268; }","duration":"110.664663ms","start":"2026-05-18T01:26:28.181644Z","end":"2026-05-18T01:26:28.292308Z","steps":["trace[8734638] 'read index received'  (duration: 46.101µs)","trace[8734638] 'applied index is now lower than readState.Index'  (duration: 110.617102ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:28.292363Z","caller":"traceutil/trace.go:171","msg":"trace[1679905808] transaction","detail":"{read_only:false; response_revision:24481; number_of_response:1; }","duration":"291.841709ms","start":"2026-05-18T01:26:28.000493Z","end":"2026-05-18T01:26:28.292335Z","steps":["trace[1679905808] 'process raft request'  (duration: 108.327248ms)","trace[1679905808] 'compare'  (duration: 183.054232ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:28.292449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.835467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:28.292481Z","caller":"traceutil/trace.go:171","msg":"trace[67498270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24481; }","duration":"110.908807ms","start":"2026-05-18T01:26:28.181564Z","end":"2026-05-18T01:26:28.292472Z","steps":["trace[67498270] 'agreement among raft nodes before linearized reading'  (duration: 110.845416ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:28.45253Z","caller":"traceutil/trace.go:171","msg":"trace[943623317] transaction","detail":"{read_only:false; response_revision:24482; number_of_response:1; }","duration":"156.267546ms","start":"2026-05-18T01:26:28.296241Z","end":"2026-05-18T01:26:28.452508Z","steps":["trace[943623317] 'process raft request'  (duration: 135.523254ms)","trace[943623317] 'compare'  (duration: 20.551688ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:28.59575Z","caller":"traceutil/trace.go:171","msg":"trace[1699695206] transaction","detail":"{read_only:false; response_revision:24483; number_of_response:1; }","duration":"298.749009ms","start":"2026-05-18T01:26:28.296977Z","end":"2026-05-18T01:26:28.595726Z","steps":["trace[1699695206] 'process raft request'  (duration: 298.561476ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:28.596029Z","caller":"traceutil/trace.go:171","msg":"trace[4626921] transaction","detail":"{read_only:false; response_revision:24484; number_of_response:1; }","duration":"299.033025ms","start":"2026-05-18T01:26:28.296969Z","end":"2026-05-18T01:26:28.596002Z","steps":["trace[4626921] 'process raft request'  (duration: 298.713529ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:28.596075Z","caller":"traceutil/trace.go:171","msg":"trace[1221022209] transaction","detail":"{read_only:false; response_revision:24485; number_of_response:1; }","duration":"299.041464ms","start":"2026-05-18T01:26:28.297009Z","end":"2026-05-18T01:26:28.59605Z","steps":["trace[1221022209] 'process raft request'  (duration: 298.898732ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:28.596152Z","caller":"traceutil/trace.go:171","msg":"trace[1796870345] transaction","detail":"{read_only:false; response_revision:24486; number_of_response:1; }","duration":"299.096216ms","start":"2026-05-18T01:26:28.29703Z","end":"2026-05-18T01:26:28.596126Z","steps":["trace[1796870345] 'process raft request'  (duration: 298.983724ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:28.745161Z","caller":"traceutil/trace.go:171","msg":"trace[1831056730] transaction","detail":"{read_only:false; response_revision:24488; number_of_response:1; }","duration":"144.191608ms","start":"2026-05-18T01:26:28.600944Z","end":"2026-05-18T01:26:28.745136Z","steps":["trace[1831056730] 'process raft request'  (duration: 142.318882ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:28.994251Z","caller":"traceutil/trace.go:171","msg":"trace[1042065272] transaction","detail":"{read_only:false; response_revision:24489; number_of_response:1; }","duration":"182.958701ms","start":"2026-05-18T01:26:28.811258Z","end":"2026-05-18T01:26:28.994217Z","steps":["trace[1042065272] 'process raft request'  (duration: 98.229048ms)","trace[1042065272] 'compare'  (duration: 84.515799ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:28.994225Z","caller":"traceutil/trace.go:171","msg":"trace[173191925] linearizableReadLoop","detail":"{readStateIndex:25277; appliedIndex:25276; }","duration":"179.27645ms","start":"2026-05-18T01:26:28.814919Z","end":"2026-05-18T01:26:28.994195Z","steps":["trace[173191925] 'read index received'  (duration: 94.561608ms)","trace[173191925] 'applied index is now lower than readState.Index'  (duration: 84.713702ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T01:26:28.994776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.394443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-18T01:26:28.994854Z","caller":"traceutil/trace.go:171","msg":"trace[1926491032] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:24489; }","duration":"179.955384ms","start":"2026-05-18T01:26:28.814876Z","end":"2026-05-18T01:26:28.994831Z","steps":["trace[1926491032] 'agreement among raft nodes before linearized reading'  (duration: 179.374432ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:29.465216Z","caller":"traceutil/trace.go:171","msg":"trace[987061430] linearizableReadLoop","detail":"{readStateIndex:25278; appliedIndex:25277; }","duration":"276.988909ms","start":"2026-05-18T01:26:29.188209Z","end":"2026-05-18T01:26:29.465198Z","steps":["trace[987061430] 'read index received'  (duration: 276.805825ms)","trace[987061430] 'applied index is now lower than readState.Index'  (duration: 182.574µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:29.465427Z","caller":"traceutil/trace.go:171","msg":"trace[586827427] transaction","detail":"{read_only:false; response_revision:24490; number_of_response:1; }","duration":"391.861821ms","start":"2026-05-18T01:26:29.073557Z","end":"2026-05-18T01:26:29.465419Z","steps":["trace[586827427] 'process raft request'  (duration: 391.508294ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:29.465498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:26:29.07353Z","time spent":"391.920402ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":42,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:24432 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:414 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"warn","ts":"2026-05-18T01:26:29.465615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.411266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:29.465637Z","caller":"traceutil/trace.go:171","msg":"trace[2061350467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24490; }","duration":"277.480748ms","start":"2026-05-18T01:26:29.18815Z","end":"2026-05-18T01:26:29.465631Z","steps":["trace[2061350467] 'agreement among raft nodes before linearized reading'  (duration: 277.445517ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:26:29.465725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.873106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:26:29.465745Z","caller":"traceutil/trace.go:171","msg":"trace[1258601480] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24490; }","duration":"276.919328ms","start":"2026-05-18T01:26:29.188819Z","end":"2026-05-18T01:26:29.465739Z","steps":["trace[1258601480] 'agreement among raft nodes before linearized reading'  (duration: 276.890527ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:29.826309Z","caller":"traceutil/trace.go:171","msg":"trace[516131059] transaction","detail":"{read_only:false; response_revision:24491; number_of_response:1; }","duration":"131.547827ms","start":"2026-05-18T01:26:29.694732Z","end":"2026-05-18T01:26:29.82628Z","steps":["trace[516131059] 'process raft request'  (duration: 131.297702ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:29.975651Z","caller":"traceutil/trace.go:171","msg":"trace[1429122282] transaction","detail":"{read_only:false; response_revision:24493; number_of_response:1; }","duration":"119.775886ms","start":"2026-05-18T01:26:29.855858Z","end":"2026-05-18T01:26:29.975634Z","steps":["trace[1429122282] 'process raft request'  (duration: 119.740435ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:29.975687Z","caller":"traceutil/trace.go:171","msg":"trace[1119347779] transaction","detail":"{read_only:false; response_revision:24492; number_of_response:1; }","duration":"135.615846ms","start":"2026-05-18T01:26:29.840056Z","end":"2026-05-18T01:26:29.975672Z","steps":["trace[1119347779] 'process raft request'  (duration: 113.12791ms)","trace[1119347779] 'compare'  (duration: 22.322152ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:26:30.117116Z","caller":"traceutil/trace.go:171","msg":"trace[837308811] transaction","detail":"{read_only:false; response_revision:24495; number_of_response:1; }","duration":"115.761779ms","start":"2026-05-18T01:26:30.001327Z","end":"2026-05-18T01:26:30.117089Z","steps":["trace[837308811] 'process raft request'  (duration: 109.023401ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:26:30.117296Z","caller":"traceutil/trace.go:171","msg":"trace[1133971158] transaction","detail":"{read_only:false; response_revision:24496; number_of_response:1; }","duration":"115.857491ms","start":"2026-05-18T01:26:30.001398Z","end":"2026-05-18T01:26:30.117256Z","steps":["trace[1133971158] 'process raft request'  (duration: 115.644537ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:27:28.957803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.232725ms","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-05-18T01:27:28.957911Z","caller":"traceutil/trace.go:171","msg":"trace[2105866399] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:25107; }","duration":"147.452739ms","start":"2026-05-18T01:27:28.810436Z","end":"2026-05-18T01:27:28.957889Z","steps":["trace[2105866399] 'range keys from in-memory index tree'  (duration: 147.143823ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:27:28.957994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.948709ms","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-05-18T01:27:28.958108Z","caller":"traceutil/trace.go:171","msg":"trace[418828913] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:25107; }","duration":"138.083052ms","start":"2026-05-18T01:27:28.82Z","end":"2026-05-18T01:27:28.958083Z","steps":["trace[418828913] 'range keys from in-memory index tree'  (duration: 137.730035ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:27:29.021791Z","caller":"traceutil/trace.go:171","msg":"trace[1600088785] linearizableReadLoop","detail":"{readStateIndex:25910; appliedIndex:25909; }","duration":"152.090826ms","start":"2026-05-18T01:27:28.869673Z","end":"2026-05-18T01:27:29.021764Z","steps":["trace[1600088785] 'read index received'  (duration: 151.876982ms)","trace[1600088785] 'applied index is now lower than readState.Index'  (duration: 212.584µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:27:29.021934Z","caller":"traceutil/trace.go:171","msg":"trace[332156938] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"168.346454ms","start":"2026-05-18T01:27:28.853572Z","end":"2026-05-18T01:27:29.021918Z","steps":["trace[332156938] 'process raft request'  (duration: 168.008807ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:27:29.022156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.473572ms","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-05-18T01:27:29.022249Z","caller":"traceutil/trace.go:171","msg":"trace[1947213024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25108; }","duration":"118.601903ms","start":"2026-05-18T01:27:28.903624Z","end":"2026-05-18T01:27:29.022226Z","steps":["trace[1947213024] 'agreement among raft nodes before linearized reading'  (duration: 118.376619ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:27:29.022417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.761287ms","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-05-18T01:27:29.022453Z","caller":"traceutil/trace.go:171","msg":"trace[1093578176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25108; }","duration":"117.794317ms","start":"2026-05-18T01:27:28.904648Z","end":"2026-05-18T01:27:29.022442Z","steps":["trace[1093578176] 'agreement among raft nodes before linearized reading'  (duration: 117.710806ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:27:29.022598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.911533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-18T01:27:29.022704Z","caller":"traceutil/trace.go:171","msg":"trace[479614550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25108; }","duration":"153.030755ms","start":"2026-05-18T01:27:28.869651Z","end":"2026-05-18T01:27:29.022682Z","steps":["trace[479614550] 'agreement among raft nodes before linearized reading'  (duration: 152.682218ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:27:29.129295Z","caller":"traceutil/trace.go:171","msg":"trace[1310705232] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"100.869167ms","start":"2026-05-18T01:27:29.028402Z","end":"2026-05-18T01:27:29.129272Z","steps":["trace[1310705232] 'process raft request'  (duration: 100.696924ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:27:29.129481Z","caller":"traceutil/trace.go:171","msg":"trace[1424197426] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"101.071111ms","start":"2026-05-18T01:27:29.028402Z","end":"2026-05-18T01:27:29.129473Z","steps":["trace[1424197426] 'process raft request'  (duration: 100.830267ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:28:06.512144Z","caller":"traceutil/trace.go:171","msg":"trace[1947333232] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"137.012771ms","start":"2026-05-18T01:28:06.375115Z","end":"2026-05-18T01:28:06.512128Z","steps":["trace[1947333232] 'process raft request'  (duration: 136.826058ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T01:28:06.51223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.848352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T01:28:06.512279Z","caller":"traceutil/trace.go:171","msg":"trace[1206302880] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25436; }","duration":"132.934984ms","start":"2026-05-18T01:28:06.379333Z","end":"2026-05-18T01:28:06.512268Z","steps":["trace[1206302880] 'agreement among raft nodes before linearized reading'  (duration: 132.846002ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:28:06.512121Z","caller":"traceutil/trace.go:171","msg":"trace[388193290] linearizableReadLoop","detail":"{readStateIndex:26247; appliedIndex:26246; }","duration":"132.73854ms","start":"2026-05-18T01:28:06.379359Z","end":"2026-05-18T01:28:06.512098Z","steps":["trace[388193290] 'read index received'  (duration: 132.626928ms)","trace[388193290] 'applied index is now lower than readState.Index'  (duration: 110.432µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T01:28:06.577559Z","caller":"traceutil/trace.go:171","msg":"trace[679306550] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"154.680236ms","start":"2026-05-18T01:28:06.422859Z","end":"2026-05-18T01:28:06.577539Z","steps":["trace[679306550] 'process raft request'  (duration: 154.568914ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T01:29:21.798793Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069a4.wal"}
{"level":"info","ts":"2026-05-18T01:30:16.476027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23833}
{"level":"info","ts":"2026-05-18T01:30:16.619352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23833,"took":"138.947059ms","hash":1104167713,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":35577856,"current-db-size-in-use":"36 MB"}
{"level":"info","ts":"2026-05-18T01:30:16.619431Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1104167713,"revision":23833,"compact-revision":20467}
{"level":"info","ts":"2026-05-18T01:35:16.48229Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26818}
{"level":"info","ts":"2026-05-18T01:35:16.623509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26818,"took":"137.184241ms","hash":4178929066,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":30814208,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-05-18T01:35:16.623569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4178929066,"revision":26818,"compact-revision":23833}
{"level":"info","ts":"2026-05-18T01:35:20.88254Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a0a01fc7fbf6be45","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-18T01:35:20.889029Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004}
{"level":"info","ts":"2026-05-18T01:35:20.889157Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004}
{"level":"info","ts":"2026-05-18T01:40:16.489433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29058}
{"level":"info","ts":"2026-05-18T01:40:16.607119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29058,"took":"113.432553ms","hash":1519977649,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-05-18T01:40:16.607173Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1519977649,"revision":29058,"compact-revision":26818}
{"level":"info","ts":"2026-05-18T01:45:16.495764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31282}
{"level":"info","ts":"2026-05-18T01:45:16.607126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31282,"took":"107.802223ms","hash":3450722311,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":32186368,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T01:45:16.607182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3450722311,"revision":31282,"compact-revision":29058}
{"level":"info","ts":"2026-05-18T01:50:16.500901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33618}
{"level":"info","ts":"2026-05-18T01:50:16.638977Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33618,"took":"134.751609ms","hash":3563514131,"current-db-size-bytes":58904576,"current-db-size":"59 MB","current-db-size-in-use-bytes":31731712,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T01:50:16.63904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3563514131,"revision":33618,"compact-revision":31282}