{"level":"warn","ts":"2026-09-01T18:35:18.005702Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-01T18:35:18.005795Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.123: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://162.253.55.123:2380","--initial-cluster=instance=https://162.253.55.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.123: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-09-01T18:35:18.005865Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-09-01T18:35:18.005876Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.123:2380"]}
{"level":"info","ts":"2026-09-01T18:35:18.005898Z","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-09-01T18:35:18.006675Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"]}
{"level":"info","ts":"2026-09-01T18:35:18.006758Z","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://162.253.55.123:2380"],"listen-peer-urls":["https://162.253.55.123:2380"],"advertise-client-urls":["https://162.253.55.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.123: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-09-01T18:35:18.010059Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.058179ms"}
{"level":"info","ts":"2026-09-01T18:35:18.022192Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dd65ba9af29ebfc9","cluster-id":"67ca29879725fb76"}
{"level":"info","ts":"2026-09-01T18:35:18.022287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=()"}
{"level":"info","ts":"2026-09-01T18:35:18.022305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 0"}
{"level":"info","ts":"2026-09-01T18:35:18.022318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dd65ba9af29ebfc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-01T18:35:18.022329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 1"}
{"level":"info","ts":"2026-09-01T18:35:18.022367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"}
{"level":"warn","ts":"2026-09-01T18:35:18.030592Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-01T18:35:18.032108Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-01T18:35:18.033478Z","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-09-01T18:35:18.03881Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dd65ba9af29ebfc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-01T18:35:18.039117Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dd65ba9af29ebfc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-01T18:35:18.039227Z","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-09-01T18:35:18.039427Z","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-09-01T18:35:18.039458Z","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-09-01T18:35:18.040421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"}
{"level":"info","ts":"2026-09-01T18:35:18.041977Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"67ca29879725fb76","local-member-id":"dd65ba9af29ebfc9","added-peer-id":"dd65ba9af29ebfc9","added-peer-peer-urls":["https://162.253.55.123:2380"]}
{"level":"info","ts":"2026-09-01T18:35:18.044653Z","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-09-01T18:35:18.044733Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.123:2380"}
{"level":"info","ts":"2026-09-01T18:35:18.044778Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.123:2380"}
{"level":"info","ts":"2026-09-01T18:35:18.044951Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dd65ba9af29ebfc9","initial-advertise-peer-urls":["https://162.253.55.123:2380"],"listen-peer-urls":["https://162.253.55.123:2380"],"advertise-client-urls":["https://162.253.55.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-01T18:35:18.044999Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-01T18:35:18.522926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 is starting a new election at term 1"}
{"level":"info","ts":"2026-09-01T18:35:18.523239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-01T18:35:18.523308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgPreVoteResp from dd65ba9af29ebfc9 at term 1"}
{"level":"info","ts":"2026-09-01T18:35:18.523334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became candidate at term 2"}
{"level":"info","ts":"2026-09-01T18:35:18.523374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgVoteResp from dd65ba9af29ebfc9 at term 2"}
{"level":"info","ts":"2026-09-01T18:35:18.523414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became leader at term 2"}
{"level":"info","ts":"2026-09-01T18:35:18.523429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dd65ba9af29ebfc9 elected leader dd65ba9af29ebfc9 at term 2"}
{"level":"info","ts":"2026-09-01T18:35:18.539951Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T18:35:18.541894Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-01T18:35:18.541885Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dd65ba9af29ebfc9","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.123:2379]}","request-path":"/0/members/dd65ba9af29ebfc9/attributes","cluster-id":"67ca29879725fb76","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-01T18:35:18.541931Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-01T18:35:18.542247Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"67ca29879725fb76","local-member-id":"dd65ba9af29ebfc9","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T18:35:18.542475Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T18:35:18.542411Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-01T18:35:18.542552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-01T18:35:18.542566Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T18:35:18.545668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.123:2379"}
{"level":"info","ts":"2026-09-01T18:35:18.546058Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-01T18:35:43.085701Z","caller":"traceutil/trace.go:171","msg":"trace[1886699098] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"107.539495ms","start":"2026-09-01T18:35:42.978138Z","end":"2026-09-01T18:35:43.085677Z","steps":["trace[1886699098] 'process raft request'  (duration: 107.397231ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:35:56.889817Z","caller":"traceutil/trace.go:171","msg":"trace[2122262200] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"102.807201ms","start":"2026-09-01T18:35:56.786983Z","end":"2026-09-01T18:35:56.88979Z","steps":["trace[2122262200] 'process raft request'  (duration: 102.637166ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:36:03.597974Z","caller":"traceutil/trace.go:171","msg":"trace[586811829] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"216.312202ms","start":"2026-09-01T18:36:03.38163Z","end":"2026-09-01T18:36:03.597942Z","steps":["trace[586811829] 'process raft request'  (duration: 215.655695ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:36:03.879996Z","caller":"traceutil/trace.go:171","msg":"trace[200405737] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"186.624506ms","start":"2026-09-01T18:36:03.693343Z","end":"2026-09-01T18:36:03.879968Z","steps":["trace[200405737] 'process raft request'  (duration: 132.961401ms)","trace[200405737] 'compare'  (duration: 53.528351ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:36:04.487153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.188867ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242181931 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/kube-system/cilium-68plx\" mod_revision:482 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-68plx\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-68plx\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T18:36:04.487331Z","caller":"traceutil/trace.go:171","msg":"trace[1964471634] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"563.1273ms","start":"2026-09-01T18:36:03.924183Z","end":"2026-09-01T18:36:04.48731Z","steps":["trace[1964471634] 'process raft request'  (duration: 369.116002ms)","trace[1964471634] 'compare'  (duration: 192.786206ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:36:04.487427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:36:03.924143Z","time spent":"563.253173ms","remote":"127.0.0.1:58076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-68plx\" mod_revision:482 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-68plx\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-68plx\" > >"}
{"level":"info","ts":"2026-09-01T18:36:04.5151Z","caller":"traceutil/trace.go:171","msg":"trace[821015632] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"184.452668ms","start":"2026-09-01T18:36:04.330587Z","end":"2026-09-01T18:36:04.51504Z","steps":["trace[821015632] 'process raft request'  (duration: 183.431811ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:36:22.051749Z","caller":"traceutil/trace.go:171","msg":"trace[849065720] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"134.268145ms","start":"2026-09-01T18:36:21.917449Z","end":"2026-09-01T18:36:22.051717Z","steps":["trace[849065720] 'process raft request'  (duration: 133.999708ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:36:22.275208Z","caller":"traceutil/trace.go:171","msg":"trace[858645180] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"202.183002ms","start":"2026-09-01T18:36:22.072993Z","end":"2026-09-01T18:36:22.275176Z","steps":["trace[858645180] 'read index received'  (duration: 118.06462ms)","trace[858645180] 'applied index is now lower than readState.Index'  (duration: 84.117292ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:36:22.275259Z","caller":"traceutil/trace.go:171","msg":"trace[816839188] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"212.999986ms","start":"2026-09-01T18:36:22.062212Z","end":"2026-09-01T18:36:22.275212Z","steps":["trace[816839188] 'process raft request'  (duration: 128.944176ms)","trace[816839188] 'compare'  (duration: 83.874265ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:36:22.275483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.458469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.123\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-09-01T18:36:22.275556Z","caller":"traceutil/trace.go:171","msg":"trace[563850436] range","detail":"{range_begin:/registry/masterleases/162.253.55.123; range_end:; response_count:1; response_revision:770; }","duration":"202.598573ms","start":"2026-09-01T18:36:22.072915Z","end":"2026-09-01T18:36:22.275513Z","steps":["trace[563850436] 'agreement among raft nodes before linearized reading'  (duration: 202.379157ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:36:55.409183Z","caller":"traceutil/trace.go:171","msg":"trace[1878215744] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"162.85563ms","start":"2026-09-01T18:36:55.246262Z","end":"2026-09-01T18:36:55.409118Z","steps":["trace[1878215744] 'process raft request'  (duration: 162.696866ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:36:55.426099Z","caller":"traceutil/trace.go:171","msg":"trace[1994249579] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"177.57193ms","start":"2026-09-01T18:36:55.248505Z","end":"2026-09-01T18:36:55.426077Z","steps":["trace[1994249579] 'process raft request'  (duration: 177.404305ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:37:44.574541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.341514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97df97eb-9256-4703-906e-501412ac1592\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-09-01T18:37:44.574646Z","caller":"traceutil/trace.go:171","msg":"trace[448132307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97df97eb-9256-4703-906e-501412ac1592; range_end:; response_count:1; response_revision:1857; }","duration":"107.499128ms","start":"2026-09-01T18:37:44.467125Z","end":"2026-09-01T18:37:44.574625Z","steps":["trace[448132307] 'range keys from in-memory index tree'  (duration: 107.124877ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:37:57.177597Z","caller":"traceutil/trace.go:171","msg":"trace[10052174] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"191.725052ms","start":"2026-09-01T18:37:56.985848Z","end":"2026-09-01T18:37:57.177573Z","steps":["trace[10052174] 'process raft request'  (duration: 191.581697ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:38:29.621612Z","caller":"traceutil/trace.go:171","msg":"trace[1671783190] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"322.641301ms","start":"2026-09-01T18:38:29.2989Z","end":"2026-09-01T18:38:29.621541Z","steps":["trace[1671783190] 'process raft request'  (duration: 305.180889ms)","trace[1671783190] 'compare'  (duration: 17.208484ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:38:29.621802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:38:29.298874Z","time spent":"322.835696ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:2109 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"info","ts":"2026-09-01T18:38:29.622927Z","caller":"traceutil/trace.go:171","msg":"trace[1713865777] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2233; }","duration":"124.800196ms","start":"2026-09-01T18:38:29.498101Z","end":"2026-09-01T18:38:29.622901Z","steps":["trace[1713865777] 'read index received'  (duration: 106.076294ms)","trace[1713865777] 'applied index is now lower than readState.Index'  (duration: 18.722752ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:38:29.623084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.969821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T18:38:29.623123Z","caller":"traceutil/trace.go:171","msg":"trace[1345838456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2151; }","duration":"125.064944ms","start":"2026-09-01T18:38:29.498047Z","end":"2026-09-01T18:38:29.623112Z","steps":["trace[1345838456] 'agreement among raft nodes before linearized reading'  (duration: 124.984471ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:38:29.623598Z","caller":"traceutil/trace.go:171","msg":"trace[29543404] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"211.656442ms","start":"2026-09-01T18:38:29.411292Z","end":"2026-09-01T18:38:29.622949Z","steps":["trace[29543404] 'process raft request'  (duration: 211.478576ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:40:24.504861Z","caller":"traceutil/trace.go:171","msg":"trace[1319712680] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"117.980584ms","start":"2026-09-01T18:40:24.386856Z","end":"2026-09-01T18:40:24.504836Z","steps":["trace[1319712680] 'process raft request'  (duration: 117.82515ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:45:18.620709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882}
{"level":"info","ts":"2026-09-01T18:45:18.699955Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"77.382341ms","hash":1453387132,"current-db-size-bytes":42938368,"current-db-size":"43 MB","current-db-size-in-use-bytes":18755584,"current-db-size-in-use":"19 MB"}
{"level":"info","ts":"2026-09-01T18:45:18.700021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1453387132,"revision":2882,"compact-revision":-1}
{"level":"info","ts":"2026-09-01T18:45:41.426303Z","caller":"traceutil/trace.go:171","msg":"trace[72672432] transaction","detail":"{read_only:false; response_revision:5555; number_of_response:1; }","duration":"140.733176ms","start":"2026-09-01T18:45:41.285544Z","end":"2026-09-01T18:45:41.426277Z","steps":["trace[72672432] 'process raft request'  (duration: 137.605955ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:45:45.482456Z","caller":"traceutil/trace.go:171","msg":"trace[310229836] transaction","detail":"{read_only:false; response_revision:5642; number_of_response:1; }","duration":"172.702997ms","start":"2026-09-01T18:45:45.309727Z","end":"2026-09-01T18:45:45.48243Z","steps":["trace[310229836] 'process raft request'  (duration: 86.525248ms)","trace[310229836] 'compare'  (duration: 85.863011ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:45:45.482465Z","caller":"traceutil/trace.go:171","msg":"trace[121796339] linearizableReadLoop","detail":"{readStateIndex:5860; appliedIndex:5859; }","duration":"135.792248ms","start":"2026-09-01T18:45:45.346637Z","end":"2026-09-01T18:45:45.482429Z","steps":["trace[121796339] 'read index received'  (duration: 49.67821ms)","trace[121796339] 'applied index is now lower than readState.Index'  (duration: 86.111408ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:45:45.482784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.120956ms","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-09-01T18:45:45.482848Z","caller":"traceutil/trace.go:171","msg":"trace[396421878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5642; }","duration":"136.251559ms","start":"2026-09-01T18:45:45.34658Z","end":"2026-09-01T18:45:45.482832Z","steps":["trace[396421878] 'agreement among raft nodes before linearized reading'  (duration: 135.977912ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:46:01.324212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.548071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5588"}
{"level":"info","ts":"2026-09-01T18:46:01.324331Z","caller":"traceutil/trace.go:171","msg":"trace[83687065] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5906; }","duration":"155.685295ms","start":"2026-09-01T18:46:01.168599Z","end":"2026-09-01T18:46:01.324284Z","steps":["trace[83687065] 'range keys from in-memory index tree'  (duration: 155.362416ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:46:01.324327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.292749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"}
{"level":"warn","ts":"2026-09-01T18:46:01.324411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.981463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-20c38e1f-4975-43d3-80fb-dd8d2db05557\" ","response":"range_response_count:1 size:3646"}
{"level":"info","ts":"2026-09-01T18:46:01.324434Z","caller":"traceutil/trace.go:171","msg":"trace[993538818] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5906; }","duration":"154.458923ms","start":"2026-09-01T18:46:01.169951Z","end":"2026-09-01T18:46:01.32441Z","steps":["trace[993538818] 'range keys from in-memory index tree'  (duration: 154.094203ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:46:01.324462Z","caller":"traceutil/trace.go:171","msg":"trace[1449493024] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-20c38e1f-4975-43d3-80fb-dd8d2db05557; range_end:; response_count:1; response_revision:5906; }","duration":"248.052904ms","start":"2026-09-01T18:46:01.076394Z","end":"2026-09-01T18:46:01.324447Z","steps":["trace[1449493024] 'range keys from in-memory index tree'  (duration: 247.87576ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:46:01.324478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.143433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T18:46:01.32462Z","caller":"traceutil/trace.go:171","msg":"trace[1762049366] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5906; }","duration":"259.325877ms","start":"2026-09-01T18:46:01.065278Z","end":"2026-09-01T18:46:01.324604Z","steps":["trace[1762049366] 'count revisions from in-memory index tree'  (duration: 259.06886ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:46:01.439644Z","caller":"traceutil/trace.go:171","msg":"trace[1346532939] transaction","detail":"{read_only:false; response_revision:5907; number_of_response:1; }","duration":"105.592733ms","start":"2026-09-01T18:46:01.334023Z","end":"2026-09-01T18:46:01.439616Z","steps":["trace[1346532939] 'process raft request'  (duration: 105.362257ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:46:01.621649Z","caller":"traceutil/trace.go:171","msg":"trace[1034187899] transaction","detail":"{read_only:false; response_revision:5915; number_of_response:1; }","duration":"163.496758ms","start":"2026-09-01T18:46:01.458128Z","end":"2026-09-01T18:46:01.621625Z","steps":["trace[1034187899] 'process raft request'  (duration: 75.538883ms)","trace[1034187899] 'compare'  (duration: 87.848122ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:46:03.583002Z","caller":"traceutil/trace.go:171","msg":"trace[501183076] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"128.239492ms","start":"2026-09-01T18:46:03.454741Z","end":"2026-09-01T18:46:03.582981Z","steps":["trace[501183076] 'process raft request'  (duration: 128.116379ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:46:53.573323Z","caller":"traceutil/trace.go:171","msg":"trace[305497721] linearizableReadLoop","detail":"{readStateIndex:6700; appliedIndex:6699; }","duration":"100.301214ms","start":"2026-09-01T18:46:53.472997Z","end":"2026-09-01T18:46:53.573299Z","steps":["trace[305497721] 'read index received'  (duration: 99.996446ms)","trace[305497721] 'applied index is now lower than readState.Index'  (duration: 303.608µs)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:46:53.573449Z","caller":"traceutil/trace.go:171","msg":"trace[848059099] transaction","detail":"{read_only:false; response_revision:6461; number_of_response:1; }","duration":"106.187129ms","start":"2026-09-01T18:46:53.46722Z","end":"2026-09-01T18:46:53.573407Z","steps":["trace[848059099] 'process raft request'  (duration: 105.907411ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:46:53.573678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.644763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"}
{"level":"info","ts":"2026-09-01T18:46:53.57378Z","caller":"traceutil/trace.go:171","msg":"trace[1287877469] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:6461; }","duration":"100.791037ms","start":"2026-09-01T18:46:53.472958Z","end":"2026-09-01T18:46:53.573749Z","steps":["trace[1287877469] 'agreement among raft nodes before linearized reading'  (duration: 100.504869ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:47:02.373289Z","caller":"traceutil/trace.go:171","msg":"trace[1845455934] transaction","detail":"{read_only:false; response_revision:6512; number_of_response:1; }","duration":"121.247286ms","start":"2026-09-01T18:47:02.252006Z","end":"2026-09-01T18:47:02.373254Z","steps":["trace[1845455934] 'process raft request'  (duration: 121.039181ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:50:18.63019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4779}
{"level":"info","ts":"2026-09-01T18:50:18.687872Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4779,"took":"56.530644ms","hash":1412055210,"current-db-size-bytes":42938368,"current-db-size":"43 MB","current-db-size-in-use-bytes":38764544,"current-db-size-in-use":"39 MB"}
{"level":"info","ts":"2026-09-01T18:50:18.687937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1412055210,"revision":4779,"compact-revision":2882}
{"level":"info","ts":"2026-09-01T18:52:11.313187Z","caller":"traceutil/trace.go:171","msg":"trace[49152215] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"355.824155ms","start":"2026-09-01T18:52:10.957331Z","end":"2026-09-01T18:52:11.313155Z","steps":["trace[49152215] 'process raft request'  (duration: 355.669421ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:11.313358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:52:10.9573Z","time spent":"355.980809ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:8794 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-01T18:52:11.324704Z","caller":"traceutil/trace.go:171","msg":"trace[1789911714] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"332.129997ms","start":"2026-09-01T18:52:10.992548Z","end":"2026-09-01T18:52:11.324678Z","steps":["trace[1789911714] 'process raft request'  (duration: 331.999894ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:11.324864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:52:10.992532Z","time spent":"332.248011ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:8795 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-01T18:52:21.955307Z","caller":"traceutil/trace.go:171","msg":"trace[1353144991] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"283.004326ms","start":"2026-09-01T18:52:21.67227Z","end":"2026-09-01T18:52:21.955274Z","steps":["trace[1353144991] 'process raft request'  (duration: 282.845662ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:22.029463Z","caller":"traceutil/trace.go:171","msg":"trace[2018065471] linearizableReadLoop","detail":"{readStateIndex:9176; appliedIndex:9174; }","duration":"308.113531ms","start":"2026-09-01T18:52:21.721307Z","end":"2026-09-01T18:52:22.029421Z","steps":["trace[2018065471] 'read index received'  (duration: 233.784953ms)","trace[2018065471] 'applied index is now lower than readState.Index'  (duration: 74.327568ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:22.029488Z","caller":"traceutil/trace.go:171","msg":"trace[1048250038] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"357.115469ms","start":"2026-09-01T18:52:21.672319Z","end":"2026-09-01T18:52:22.029434Z","steps":["trace[1048250038] 'process raft request'  (duration: 322.293947ms)","trace[1048250038] 'compare'  (duration: 34.651487ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:22.029555Z","caller":"traceutil/trace.go:171","msg":"trace[767927877] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"175.841758ms","start":"2026-09-01T18:52:21.853677Z","end":"2026-09-01T18:52:22.029519Z","steps":["trace[767927877] 'process raft request'  (duration: 175.697074ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:22.029643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:52:21.672293Z","time spent":"357.273823ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:8847 > 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-09-01T18:52:22.02968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.379179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"}
{"level":"info","ts":"2026-09-01T18:52:22.029721Z","caller":"traceutil/trace.go:171","msg":"trace[558799131] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8860; }","duration":"308.477031ms","start":"2026-09-01T18:52:21.721229Z","end":"2026-09-01T18:52:22.029706Z","steps":["trace[558799131] 'agreement among raft nodes before linearized reading'  (duration: 308.330147ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:22.02975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:52:21.721207Z","time spent":"308.534812ms","remote":"127.0.0.1:58088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "}
{"level":"warn","ts":"2026-09-01T18:52:22.029834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.911692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qbfw5\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-01T18:52:22.029904Z","caller":"traceutil/trace.go:171","msg":"trace[1283781504] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qbfw5; range_end:; response_count:1; response_revision:8860; }","duration":"124.021655ms","start":"2026-09-01T18:52:21.905866Z","end":"2026-09-01T18:52:22.029888Z","steps":["trace[1283781504] 'agreement among raft nodes before linearized reading'  (duration: 123.85123ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:22.030018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.084631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-d47898cd6-4w5fc.18d1471dc4d0ce37\" ","response":"range_response_count:1 size:1132"}
{"level":"info","ts":"2026-09-01T18:52:22.030073Z","caller":"traceutil/trace.go:171","msg":"trace[1047816181] range","detail":"{range_begin:/registry/events/openstack/barbican-api-d47898cd6-4w5fc.18d1471dc4d0ce37; range_end:; response_count:1; response_revision:8860; }","duration":"103.140522ms","start":"2026-09-01T18:52:21.926914Z","end":"2026-09-01T18:52:22.030055Z","steps":["trace[1047816181] 'agreement among raft nodes before linearized reading'  (duration: 102.983138ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:22.65851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.1522ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242202277 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:8853 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T18:52:22.658576Z","caller":"traceutil/trace.go:171","msg":"trace[1621541550] linearizableReadLoop","detail":"{readStateIndex:9185; appliedIndex:9184; }","duration":"252.861288ms","start":"2026-09-01T18:52:22.405704Z","end":"2026-09-01T18:52:22.658565Z","steps":["trace[1621541550] 'read index received'  (duration: 137.557784ms)","trace[1621541550] 'applied index is now lower than readState.Index'  (duration: 115.302574ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:22.658623Z","caller":"traceutil/trace.go:171","msg":"trace[1768307723] transaction","detail":"{read_only:false; response_revision:8866; number_of_response:1; }","duration":"257.871691ms","start":"2026-09-01T18:52:22.400745Z","end":"2026-09-01T18:52:22.658616Z","steps":["trace[1768307723] 'process raft request'  (duration: 142.538686ms)","trace[1768307723] 'compare'  (duration: 114.986576ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:52:22.658723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.046474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T18:52:22.658809Z","caller":"traceutil/trace.go:171","msg":"trace[1616534461] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:8866; }","duration":"190.186818ms","start":"2026-09-01T18:52:22.468604Z","end":"2026-09-01T18:52:22.65879Z","steps":["trace[1616534461] 'agreement among raft nodes before linearized reading'  (duration: 190.039524ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:22.658826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.84437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T18:52:22.658846Z","caller":"traceutil/trace.go:171","msg":"trace[15636321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8866; }","duration":"160.882502ms","start":"2026-09-01T18:52:22.497958Z","end":"2026-09-01T18:52:22.65884Z","steps":["trace[15636321] 'agreement among raft nodes before linearized reading'  (duration: 160.834071ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:22.658911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.200357ms","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-09-01T18:52:22.658959Z","caller":"traceutil/trace.go:171","msg":"trace[965659238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8866; }","duration":"253.284959ms","start":"2026-09-01T18:52:22.405662Z","end":"2026-09-01T18:52:22.658947Z","steps":["trace[965659238] 'agreement among raft nodes before linearized reading'  (duration: 253.134965ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:22.65891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.666771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-01T18:52:22.659048Z","caller":"traceutil/trace.go:171","msg":"trace[744655449] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8866; }","duration":"187.850506ms","start":"2026-09-01T18:52:22.471185Z","end":"2026-09-01T18:52:22.659035Z","steps":["trace[744655449] 'agreement among raft nodes before linearized reading'  (duration: 187.61449ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:23.75283Z","caller":"traceutil/trace.go:171","msg":"trace[1933857402] transaction","detail":"{read_only:false; response_revision:8872; number_of_response:1; }","duration":"193.638769ms","start":"2026-09-01T18:52:23.559156Z","end":"2026-09-01T18:52:23.752795Z","steps":["trace[1933857402] 'process raft request'  (duration: 151.156484ms)","trace[1933857402] 'compare'  (duration: 42.089284ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:52.337767Z","caller":"traceutil/trace.go:171","msg":"trace[56259378] linearizableReadLoop","detail":"{readStateIndex:9403; appliedIndex:9402; }","duration":"175.009655ms","start":"2026-09-01T18:52:52.162735Z","end":"2026-09-01T18:52:52.337745Z","steps":["trace[56259378] 'read index received'  (duration: 174.870882ms)","trace[56259378] 'applied index is now lower than readState.Index'  (duration: 137.933µs)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:52.33783Z","caller":"traceutil/trace.go:171","msg":"trace[803819817] transaction","detail":"{read_only:false; response_revision:9077; number_of_response:1; }","duration":"196.281279ms","start":"2026-09-01T18:52:52.141517Z","end":"2026-09-01T18:52:52.337798Z","steps":["trace[803819817] 'process raft request'  (duration: 196.093714ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:52.338042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.287723ms","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-09-01T18:52:52.338134Z","caller":"traceutil/trace.go:171","msg":"trace[163042534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9077; }","duration":"175.440377ms","start":"2026-09-01T18:52:52.162672Z","end":"2026-09-01T18:52:52.338112Z","steps":["trace[163042534] 'agreement among raft nodes before linearized reading'  (duration: 175.17573ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:52.411963Z","caller":"traceutil/trace.go:171","msg":"trace[1174295006] transaction","detail":"{read_only:false; response_revision:9078; number_of_response:1; }","duration":"221.929909ms","start":"2026-09-01T18:52:52.190014Z","end":"2026-09-01T18:52:52.411944Z","steps":["trace[1174295006] 'process raft request'  (duration: 221.626991ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:52.412127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.573035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qbfw5\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-01T18:52:52.412203Z","caller":"traceutil/trace.go:171","msg":"trace[1894140878] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qbfw5; range_end:; response_count:1; response_revision:9079; }","duration":"191.694548ms","start":"2026-09-01T18:52:52.220491Z","end":"2026-09-01T18:52:52.412185Z","steps":["trace[1894140878] 'agreement among raft nodes before linearized reading'  (duration: 191.496613ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:52.64053Z","caller":"traceutil/trace.go:171","msg":"trace[2050981747] linearizableReadLoop","detail":"{readStateIndex:9407; appliedIndex:9406; }","duration":"224.179398ms","start":"2026-09-01T18:52:52.416311Z","end":"2026-09-01T18:52:52.640491Z","steps":["trace[2050981747] 'read index received'  (duration: 133.976239ms)","trace[2050981747] 'applied index is now lower than readState.Index'  (duration: 90.200789ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:52.640543Z","caller":"traceutil/trace.go:171","msg":"trace[1750393448] transaction","detail":"{read_only:false; response_revision:9080; number_of_response:1; }","duration":"226.826628ms","start":"2026-09-01T18:52:52.413679Z","end":"2026-09-01T18:52:52.640506Z","steps":["trace[1750393448] 'process raft request'  (duration: 136.651699ms)","trace[1750393448] 'compare'  (duration: 89.907272ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:52:52.640845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.463848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-01T18:52:52.640833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.69447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T18:52:52.640896Z","caller":"traceutil/trace.go:171","msg":"trace[679493352] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:9080; }","duration":"112.591572ms","start":"2026-09-01T18:52:52.52829Z","end":"2026-09-01T18:52:52.640882Z","steps":["trace[679493352] 'agreement among raft nodes before linearized reading'  (duration: 112.452638ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:52.640915Z","caller":"traceutil/trace.go:171","msg":"trace[627080358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9080; }","duration":"142.897575ms","start":"2026-09-01T18:52:52.497997Z","end":"2026-09-01T18:52:52.640895Z","steps":["trace[627080358] 'agreement among raft nodes before linearized reading'  (duration: 142.70225ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:52.641073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.737023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228540"}
{"level":"info","ts":"2026-09-01T18:52:52.641169Z","caller":"traceutil/trace.go:171","msg":"trace[967706398] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:9080; }","duration":"224.865456ms","start":"2026-09-01T18:52:52.416282Z","end":"2026-09-01T18:52:52.641147Z","steps":["trace[967706398] 'agreement among raft nodes before linearized reading'  (duration: 224.28647ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:52.844848Z","caller":"traceutil/trace.go:171","msg":"trace[2115958305] linearizableReadLoop","detail":"{readStateIndex:9408; appliedIndex:9407; }","duration":"199.088994ms","start":"2026-09-01T18:52:52.645726Z","end":"2026-09-01T18:52:52.844815Z","steps":["trace[2115958305] 'read index received'  (duration: 148.262187ms)","trace[2115958305] 'applied index is now lower than readState.Index'  (duration: 50.826027ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:52.844934Z","caller":"traceutil/trace.go:171","msg":"trace[974244108] transaction","detail":"{read_only:false; response_revision:9081; number_of_response:1; }","duration":"199.798022ms","start":"2026-09-01T18:52:52.645098Z","end":"2026-09-01T18:52:52.844896Z","steps":["trace[974244108] 'process raft request'  (duration: 149.009126ms)","trace[974244108] 'compare'  (duration: 50.56762ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:52:52.845044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.297529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-09-01T18:52:52.845125Z","caller":"traceutil/trace.go:171","msg":"trace[1611972300] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:9081; }","duration":"199.401131ms","start":"2026-09-01T18:52:52.645701Z","end":"2026-09-01T18:52:52.845102Z","steps":["trace[1611972300] 'agreement among raft nodes before linearized reading'  (duration: 199.210876ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:58.544434Z","caller":"traceutil/trace.go:171","msg":"trace[643770962] linearizableReadLoop","detail":"{readStateIndex:9438; appliedIndex:9437; }","duration":"110.836816ms","start":"2026-09-01T18:52:58.433582Z","end":"2026-09-01T18:52:58.544419Z","steps":["trace[643770962] 'read index received'  (duration: 110.715993ms)","trace[643770962] 'applied index is now lower than readState.Index'  (duration: 119.873µs)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:58.544532Z","caller":"traceutil/trace.go:171","msg":"trace[45524668] transaction","detail":"{read_only:false; response_revision:9110; number_of_response:1; }","duration":"172.89638ms","start":"2026-09-01T18:52:58.371628Z","end":"2026-09-01T18:52:58.544525Z","steps":["trace[45524668] 'process raft request'  (duration: 172.659184ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:58.544678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.085233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-09-01T18:52:58.544716Z","caller":"traceutil/trace.go:171","msg":"trace[1890442040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9110; }","duration":"111.171895ms","start":"2026-09-01T18:52:58.433536Z","end":"2026-09-01T18:52:58.544708Z","steps":["trace[1890442040] 'agreement among raft nodes before linearized reading'  (duration: 111.032871ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:58.544883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.169392ms","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-09-01T18:52:58.544989Z","caller":"traceutil/trace.go:171","msg":"trace[105039766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9110; }","duration":"109.336567ms","start":"2026-09-01T18:52:58.43563Z","end":"2026-09-01T18:52:58.544967Z","steps":["trace[105039766] 'agreement among raft nodes before linearized reading'  (duration: 108.997688ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:52:58.710294Z","caller":"traceutil/trace.go:171","msg":"trace[1135208550] transaction","detail":"{read_only:false; response_revision:9111; number_of_response:1; }","duration":"161.98766ms","start":"2026-09-01T18:52:58.548276Z","end":"2026-09-01T18:52:58.710264Z","steps":["trace[1135208550] 'process raft request'  (duration: 122.553116ms)","trace[1135208550] 'compare'  (duration: 39.162157ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T18:52:58.710682Z","caller":"traceutil/trace.go:171","msg":"trace[1375249912] transaction","detail":"{read_only:false; response_revision:9112; number_of_response:1; }","duration":"161.910198ms","start":"2026-09-01T18:52:58.548756Z","end":"2026-09-01T18:52:58.710666Z","steps":["trace[1375249912] 'process raft request'  (duration: 161.448126ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:52:59.237261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.166592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T18:52:59.237477Z","caller":"traceutil/trace.go:171","msg":"trace[2065870182] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:9116; }","duration":"146.48252ms","start":"2026-09-01T18:52:59.090905Z","end":"2026-09-01T18:52:59.237388Z","steps":["trace[2065870182] 'count revisions from in-memory index tree'  (duration: 145.970077ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T18:53:06.180855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.924303ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242203325 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/openstack/rook-ceph-detect-version-rbcqq\" mod_revision:9191 > success:<request_put:<key:\"/registry/pods/openstack/rook-ceph-detect-version-rbcqq\" value_size:4575 >> failure:<request_range:<key:\"/registry/pods/openstack/rook-ceph-detect-version-rbcqq\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T18:53:06.180982Z","caller":"traceutil/trace.go:171","msg":"trace[1489540887] transaction","detail":"{read_only:false; response_revision:9195; number_of_response:1; }","duration":"325.342008ms","start":"2026-09-01T18:53:05.855616Z","end":"2026-09-01T18:53:06.180958Z","steps":["trace[1489540887] 'process raft request'  (duration: 113.203959ms)","trace[1489540887] 'compare'  (duration: 211.78851ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:53:06.181082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:53:05.855597Z","time spent":"325.42505ms","remote":"127.0.0.1:58076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/rook-ceph-detect-version-rbcqq\" mod_revision:9191 > success:<request_put:<key:\"/registry/pods/openstack/rook-ceph-detect-version-rbcqq\" value_size:4575 >> failure:<request_range:<key:\"/registry/pods/openstack/rook-ceph-detect-version-rbcqq\" > >"}
{"level":"info","ts":"2026-09-01T18:53:19.740298Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000025b1.wal"}
{"level":"info","ts":"2026-09-01T18:54:00.588132Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd65ba9af29ebfc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-01T18:54:00.5966Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-09-01T18:54:00.596762Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-09-01T18:55:18.636846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7826}
{"level":"info","ts":"2026-09-01T18:55:18.744128Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7826,"took":"105.29329ms","hash":3045190409,"current-db-size-bytes":43601920,"current-db-size":"44 MB","current-db-size-in-use-bytes":26071040,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-01T18:55:18.744194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3045190409,"revision":7826,"compact-revision":4779}
{"level":"info","ts":"2026-09-01T18:58:09.93765Z","caller":"traceutil/trace.go:171","msg":"trace[2000987499] linearizableReadLoop","detail":"{readStateIndex:12214; appliedIndex:12213; }","duration":"105.951855ms","start":"2026-09-01T18:58:09.831675Z","end":"2026-09-01T18:58:09.937627Z","steps":["trace[2000987499] 'read index received'  (duration: 105.684107ms)","trace[2000987499] 'applied index is now lower than readState.Index'  (duration: 266.858µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T18:58:09.937826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.135689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T18:58:09.937885Z","caller":"traceutil/trace.go:171","msg":"trace[800571931] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:11804; }","duration":"106.257822ms","start":"2026-09-01T18:58:09.831614Z","end":"2026-09-01T18:58:09.937872Z","steps":["trace[800571931] 'agreement among raft nodes before linearized reading'  (duration: 106.138559ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T18:58:09.937969Z","caller":"traceutil/trace.go:171","msg":"trace[1980198908] transaction","detail":"{read_only:false; response_revision:11804; number_of_response:1; }","duration":"140.473865ms","start":"2026-09-01T18:58:09.797454Z","end":"2026-09-01T18:58:09.937928Z","steps":["trace[1980198908] 'process raft request'  (duration: 139.963101ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:00:18.651364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10349}
{"level":"info","ts":"2026-09-01T19:00:18.73654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10349,"took":"82.952856ms","hash":1066666685,"current-db-size-bytes":43601920,"current-db-size":"44 MB","current-db-size-in-use-bytes":28110848,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-01T19:00:18.736781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1066666685,"revision":10349,"compact-revision":7826}
{"level":"info","ts":"2026-09-01T19:02:44.018516Z","caller":"traceutil/trace.go:171","msg":"trace[489811516] transaction","detail":"{read_only:false; response_revision:13672; number_of_response:1; }","duration":"116.321247ms","start":"2026-09-01T19:02:43.902142Z","end":"2026-09-01T19:02:44.018463Z","steps":["trace[489811516] 'process raft request'  (duration: 97.318159ms)","trace[489811516] 'compare'  (duration: 18.818613ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:05:09.027815Z","caller":"traceutil/trace.go:171","msg":"trace[335193713] transaction","detail":"{read_only:false; response_revision:14580; number_of_response:1; }","duration":"137.891313ms","start":"2026-09-01T19:05:08.889885Z","end":"2026-09-01T19:05:09.027777Z","steps":["trace[335193713] 'process raft request'  (duration: 136.805314ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:05:18.658287Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12705}
{"level":"info","ts":"2026-09-01T19:05:18.760698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12705,"took":"100.068913ms","hash":340191479,"current-db-size-bytes":43601920,"current-db-size":"44 MB","current-db-size-in-use-bytes":27283456,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-09-01T19:05:18.76077Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":340191479,"revision":12705,"compact-revision":10349}
{"level":"warn","ts":"2026-09-01T19:05:33.104266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.362469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qbfw5\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-09-01T19:05:33.104475Z","caller":"traceutil/trace.go:171","msg":"trace[1823258709] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qbfw5; range_end:; response_count:1; response_revision:14710; }","duration":"155.571844ms","start":"2026-09-01T19:05:32.948837Z","end":"2026-09-01T19:05:33.104409Z","steps":["trace[1823258709] 'range keys from in-memory index tree'  (duration: 155.177534ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:33.104619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.137024ms","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-09-01T19:05:33.104701Z","caller":"traceutil/trace.go:171","msg":"trace[1328413148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14710; }","duration":"170.264118ms","start":"2026-09-01T19:05:32.934416Z","end":"2026-09-01T19:05:33.10468Z","steps":["trace[1328413148] 'range keys from in-memory index tree'  (duration: 169.913929ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:33.104742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.179436ms","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-09-01T19:05:33.104821Z","caller":"traceutil/trace.go:171","msg":"trace[337504973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14710; }","duration":"191.32823ms","start":"2026-09-01T19:05:32.913474Z","end":"2026-09-01T19:05:33.104803Z","steps":["trace[337504973] 'range keys from in-memory index tree'  (duration: 190.96724ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:05:45.686996Z","caller":"traceutil/trace.go:171","msg":"trace[1738778636] transaction","detail":"{read_only:false; response_revision:14792; number_of_response:1; }","duration":"122.682507ms","start":"2026-09-01T19:05:45.564288Z","end":"2026-09-01T19:05:45.686971Z","steps":["trace[1738778636] 'process raft request'  (duration: 122.524043ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:05:52.660774Z","caller":"traceutil/trace.go:171","msg":"trace[257363378] linearizableReadLoop","detail":"{readStateIndex:15349; appliedIndex:15348; }","duration":"161.650797ms","start":"2026-09-01T19:05:52.499099Z","end":"2026-09-01T19:05:52.66075Z","steps":["trace[257363378] 'read index received'  (duration: 161.532324ms)","trace[257363378] 'applied index is now lower than readState.Index'  (duration: 117.493µs)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:05:52.660853Z","caller":"traceutil/trace.go:171","msg":"trace[1053080102] transaction","detail":"{read_only:false; response_revision:14830; number_of_response:1; }","duration":"293.15768ms","start":"2026-09-01T19:05:52.367652Z","end":"2026-09-01T19:05:52.66081Z","steps":["trace[1053080102] 'process raft request'  (duration: 292.959024ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:52.660933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.791331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T19:05:52.66098Z","caller":"traceutil/trace.go:171","msg":"trace[1243474907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14830; }","duration":"161.924875ms","start":"2026-09-01T19:05:52.499047Z","end":"2026-09-01T19:05:52.660972Z","steps":["trace[1243474907] 'agreement among raft nodes before linearized reading'  (duration: 161.788831ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:53.22099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.969305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T19:05:53.221074Z","caller":"traceutil/trace.go:171","msg":"trace[1538926662] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:14830; }","duration":"536.092247ms","start":"2026-09-01T19:05:52.684961Z","end":"2026-09-01T19:05:53.221053Z","steps":["trace[1538926662] 'count revisions from in-memory index tree'  (duration: 535.905802ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:53.221112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:05:52.68495Z","time spent":"536.15194ms","remote":"127.0.0.1:53944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "}
{"level":"warn","ts":"2026-09-01T19:05:53.221794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.207775ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242219097 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:14817 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T19:05:53.221895Z","caller":"traceutil/trace.go:171","msg":"trace[704692608] linearizableReadLoop","detail":"{readStateIndex:15350; appliedIndex:15349; }","duration":"355.042012ms","start":"2026-09-01T19:05:52.866839Z","end":"2026-09-01T19:05:53.221881Z","steps":["trace[704692608] 'read index received'  (duration: 126.701834ms)","trace[704692608] 'applied index is now lower than readState.Index'  (duration: 228.338888ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:05:53.221991Z","caller":"traceutil/trace.go:171","msg":"trace[1760162863] transaction","detail":"{read_only:false; response_revision:14831; number_of_response:1; }","duration":"449.424063ms","start":"2026-09-01T19:05:52.772545Z","end":"2026-09-01T19:05:53.221969Z","steps":["trace[1760162863] 'process raft request'  (duration: 220.986252ms)","trace[1760162863] 'compare'  (duration: 227.392753ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:05:53.222109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:05:52.772515Z","time spent":"449.526916ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:14817 > 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-09-01T19:05:53.222386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.535121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"warn","ts":"2026-09-01T19:05:53.222408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.506761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-09-01T19:05:53.222431Z","caller":"traceutil/trace.go:171","msg":"trace[1300721255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14831; }","duration":"338.606664ms","start":"2026-09-01T19:05:52.883814Z","end":"2026-09-01T19:05:53.222421Z","steps":["trace[1300721255] 'agreement among raft nodes before linearized reading'  (duration: 338.344557ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:05:53.222448Z","caller":"traceutil/trace.go:171","msg":"trace[1182702340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14831; }","duration":"338.589443ms","start":"2026-09-01T19:05:52.883847Z","end":"2026-09-01T19:05:53.222436Z","steps":["trace[1182702340] 'agreement among raft nodes before linearized reading'  (duration: 338.439889ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:53.222463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:05:52.883801Z","time spent":"338.654365ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-01T19:05:53.222488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:05:52.883823Z","time spent":"338.656564ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-09-01T19:05:53.223293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.300959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930214"}
{"level":"info","ts":"2026-09-01T19:05:53.22334Z","caller":"traceutil/trace.go:171","msg":"trace[41841768] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:14830; }","duration":"513.384101ms","start":"2026-09-01T19:05:52.709945Z","end":"2026-09-01T19:05:53.223329Z","steps":["trace[41841768] 'range keys from in-memory index tree'  (duration: 510.839564ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:53.223494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:05:52.709929Z","time spent":"513.433113ms","remote":"127.0.0.1:57984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":930237,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "}
{"level":"warn","ts":"2026-09-01T19:05:53.222203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.35032ms","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-09-01T19:05:53.224652Z","caller":"traceutil/trace.go:171","msg":"trace[1186188003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14831; }","duration":"357.799345ms","start":"2026-09-01T19:05:52.866822Z","end":"2026-09-01T19:05:53.224621Z","steps":["trace[1186188003] 'agreement among raft nodes before linearized reading'  (duration: 355.181196ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:05:53.224716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:05:52.866757Z","time spent":"357.94002ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-09-01T19:07:37.71706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.08648ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242223021 > lease_revoke:<id:3fc9a05e411329ef>","response":"size:28"}
{"level":"info","ts":"2026-09-01T19:07:37.717285Z","caller":"traceutil/trace.go:171","msg":"trace[823457881] linearizableReadLoop","detail":"{readStateIndex:16606; appliedIndex:16605; }","duration":"288.524476ms","start":"2026-09-01T19:07:37.428745Z","end":"2026-09-01T19:07:37.717269Z","steps":["trace[823457881] 'read index received'  (duration: 116.155168ms)","trace[823457881] 'applied index is now lower than readState.Index'  (duration: 172.368368ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:07:37.718034Z","caller":"traceutil/trace.go:171","msg":"trace[63733121] transaction","detail":"{read_only:false; response_revision:16053; number_of_response:1; }","duration":"112.142812ms","start":"2026-09-01T19:07:37.605872Z","end":"2026-09-01T19:07:37.718015Z","steps":["trace[63733121] 'process raft request'  (duration: 111.28531ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:37.726128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.36916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pflwm\" ","response":"range_response_count:1 size:22125"}
{"level":"warn","ts":"2026-09-01T19:07:37.726143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.288727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T19:07:37.726195Z","caller":"traceutil/trace.go:171","msg":"trace[167885986] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pflwm; range_end:; response_count:1; response_revision:16053; }","duration":"297.481983ms","start":"2026-09-01T19:07:37.428696Z","end":"2026-09-01T19:07:37.726178Z","steps":["trace[167885986] 'agreement among raft nodes before linearized reading'  (duration: 289.119392ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:07:37.726198Z","caller":"traceutil/trace.go:171","msg":"trace[1450426247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16053; }","duration":"229.390669ms","start":"2026-09-01T19:07:37.496794Z","end":"2026-09-01T19:07:37.726184Z","steps":["trace[1450426247] 'agreement among raft nodes before linearized reading'  (duration: 221.271375ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:37.726278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.924672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"}
{"level":"info","ts":"2026-09-01T19:07:37.726354Z","caller":"traceutil/trace.go:171","msg":"trace[734573698] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16053; }","duration":"193.042416ms","start":"2026-09-01T19:07:37.533289Z","end":"2026-09-01T19:07:37.726332Z","steps":["trace[734573698] 'agreement among raft nodes before linearized reading'  (duration: 184.770477ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:37.726359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.53581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pflwm\" ","response":"range_response_count:1 size:22125"}
{"level":"info","ts":"2026-09-01T19:07:37.726585Z","caller":"traceutil/trace.go:171","msg":"trace[406008252] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pflwm; range_end:; response_count:1; response_revision:16053; }","duration":"247.795656ms","start":"2026-09-01T19:07:37.478766Z","end":"2026-09-01T19:07:37.726562Z","steps":["trace[406008252] 'agreement among raft nodes before linearized reading'  (duration: 239.218789ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:07:37.979412Z","caller":"traceutil/trace.go:171","msg":"trace[320540971] transaction","detail":"{read_only:false; response_revision:16054; number_of_response:1; }","duration":"159.589059ms","start":"2026-09-01T19:07:37.819799Z","end":"2026-09-01T19:07:37.979388Z","steps":["trace[320540971] 'process raft request'  (duration: 159.443235ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:07:37.979587Z","caller":"traceutil/trace.go:171","msg":"trace[1260958331] linearizableReadLoop","detail":"{readStateIndex:16608; appliedIndex:16608; }","duration":"157.659168ms","start":"2026-09-01T19:07:37.821907Z","end":"2026-09-01T19:07:37.979566Z","steps":["trace[1260958331] 'read index received'  (duration: 157.650428ms)","trace[1260958331] 'applied index is now lower than readState.Index'  (duration: 6.69µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:07:37.979774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.852373ms","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-09-01T19:07:37.979804Z","caller":"traceutil/trace.go:171","msg":"trace[1249032395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16054; }","duration":"157.908724ms","start":"2026-09-01T19:07:37.821886Z","end":"2026-09-01T19:07:37.979794Z","steps":["trace[1249032395] 'agreement among raft nodes before linearized reading'  (duration: 157.763961ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:07:38.008833Z","caller":"traceutil/trace.go:171","msg":"trace[671002935] transaction","detail":"{read_only:false; response_revision:16055; number_of_response:1; }","duration":"154.654879ms","start":"2026-09-01T19:07:37.854125Z","end":"2026-09-01T19:07:38.00878Z","steps":["trace[671002935] 'process raft request'  (duration: 153.868828ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:38.008883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.911915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-09-01T19:07:38.00892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.263729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"}
{"level":"info","ts":"2026-09-01T19:07:38.009019Z","caller":"traceutil/trace.go:171","msg":"trace[1291957798] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16055; }","duration":"111.409042ms","start":"2026-09-01T19:07:37.897583Z","end":"2026-09-01T19:07:38.008992Z","steps":["trace[1291957798] 'agreement among raft nodes before linearized reading'  (duration: 111.152656ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:38.009054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.587998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"warn","ts":"2026-09-01T19:07:38.00906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.750228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-01T19:07:38.009096Z","caller":"traceutil/trace.go:171","msg":"trace[1072385218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16055; }","duration":"168.640439ms","start":"2026-09-01T19:07:37.840443Z","end":"2026-09-01T19:07:38.009084Z","steps":["trace[1072385218] 'agreement among raft nodes before linearized reading'  (duration: 168.534486ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:07:38.009131Z","caller":"traceutil/trace.go:171","msg":"trace[372115638] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16055; }","duration":"155.84047ms","start":"2026-09-01T19:07:37.853273Z","end":"2026-09-01T19:07:38.009114Z","steps":["trace[372115638] 'agreement among raft nodes before linearized reading'  (duration: 155.648335ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:07:38.008946Z","caller":"traceutil/trace.go:171","msg":"trace[237150986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16055; }","duration":"181.018538ms","start":"2026-09-01T19:07:37.827908Z","end":"2026-09-01T19:07:38.008927Z","steps":["trace[237150986] 'agreement among raft nodes before linearized reading'  (duration: 180.802282ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:38.009213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.857231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-09-01T19:07:38.009264Z","caller":"traceutil/trace.go:171","msg":"trace[1601351723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16055; }","duration":"155.949393ms","start":"2026-09-01T19:07:37.8533Z","end":"2026-09-01T19:07:38.009249Z","steps":["trace[1601351723] 'agreement among raft nodes before linearized reading'  (duration: 155.81492ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:38.009276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.516356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-01T19:07:38.009269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.414724ms","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-09-01T19:07:38.009331Z","caller":"traceutil/trace.go:171","msg":"trace[1481278117] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16055; }","duration":"111.611088ms","start":"2026-09-01T19:07:37.897707Z","end":"2026-09-01T19:07:38.009318Z","steps":["trace[1481278117] 'agreement among raft nodes before linearized reading'  (duration: 111.529166ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:07:38.009365Z","caller":"traceutil/trace.go:171","msg":"trace[802446078] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16055; }","duration":"105.561518ms","start":"2026-09-01T19:07:37.90378Z","end":"2026-09-01T19:07:38.009342Z","steps":["trace[802446078] 'agreement among raft nodes before linearized reading'  (duration: 105.295571ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:38.009436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.789716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"}
{"level":"info","ts":"2026-09-01T19:07:38.009548Z","caller":"traceutil/trace.go:171","msg":"trace[1842409966] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16055; }","duration":"170.950591ms","start":"2026-09-01T19:07:37.838573Z","end":"2026-09-01T19:07:38.009523Z","steps":["trace[1842409966] 'agreement among raft nodes before linearized reading'  (duration: 170.600671ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:07:38.009574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.880394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T19:07:38.009622Z","caller":"traceutil/trace.go:171","msg":"trace[2092372215] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:16055; }","duration":"137.964767ms","start":"2026-09-01T19:07:37.871637Z","end":"2026-09-01T19:07:38.009602Z","steps":["trace[2092372215] 'agreement among raft nodes before linearized reading'  (duration: 137.888005ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:10:18.664363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14632}
{"level":"info","ts":"2026-09-01T19:10:18.748276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14632,"took":"81.049948ms","hash":743380601,"current-db-size-bytes":44023808,"current-db-size":"44 MB","current-db-size-in-use-bytes":31662080,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-01T19:10:18.748342Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":743380601,"revision":14632,"compact-revision":12705}
{"level":"info","ts":"2026-09-01T19:13:14.557084Z","caller":"traceutil/trace.go:171","msg":"trace[1950557963] linearizableReadLoop","detail":"{readStateIndex:19170; appliedIndex:19169; }","duration":"216.137865ms","start":"2026-09-01T19:13:14.340922Z","end":"2026-09-01T19:13:14.55706Z","steps":["trace[1950557963] 'read index received'  (duration: 175.390498ms)","trace[1950557963] 'applied index is now lower than readState.Index'  (duration: 40.746487ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:13:14.557204Z","caller":"traceutil/trace.go:171","msg":"trace[1084089067] transaction","detail":"{read_only:false; response_revision:18533; number_of_response:1; }","duration":"218.019565ms","start":"2026-09-01T19:13:14.339174Z","end":"2026-09-01T19:13:14.557193Z","steps":["trace[1084089067] 'process raft request'  (duration: 177.161454ms)","trace[1084089067] 'compare'  (duration: 40.621175ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:13:14.557631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.68749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-01T19:13:14.55772Z","caller":"traceutil/trace.go:171","msg":"trace[1907722586] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18533; }","duration":"216.830363ms","start":"2026-09-01T19:13:14.340868Z","end":"2026-09-01T19:13:14.557699Z","steps":["trace[1907722586] 'agreement among raft nodes before linearized reading'  (duration: 216.683509ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:13:14.557706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.438658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pflwm\" ","response":"range_response_count:1 size:22126"}
{"level":"info","ts":"2026-09-01T19:13:14.557788Z","caller":"traceutil/trace.go:171","msg":"trace[1532664756] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pflwm; range_end:; response_count:1; response_revision:18533; }","duration":"136.569321ms","start":"2026-09-01T19:13:14.421196Z","end":"2026-09-01T19:13:14.557766Z","steps":["trace[1532664756] 'agreement among raft nodes before linearized reading'  (duration: 136.330975ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:13:18.774441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.591641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c426c4dd-5878-4226-8321-4c5b6f1b5835\" ","response":"range_response_count:1 size:3640"}
{"level":"warn","ts":"2026-09-01T19:13:18.77453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.307904ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242232680 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:18545 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-01T19:13:18.774562Z","caller":"traceutil/trace.go:171","msg":"trace[1640592431] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c426c4dd-5878-4226-8321-4c5b6f1b5835; range_end:; response_count:1; response_revision:18611; }","duration":"215.754286ms","start":"2026-09-01T19:13:18.558784Z","end":"2026-09-01T19:13:18.774538Z","steps":["trace[1640592431] 'range keys from in-memory index tree'  (duration: 215.416036ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:13:18.774638Z","caller":"traceutil/trace.go:171","msg":"trace[342910281] linearizableReadLoop","detail":"{readStateIndex:19251; appliedIndex:19250; }","duration":"163.912815ms","start":"2026-09-01T19:13:18.610706Z","end":"2026-09-01T19:13:18.774619Z","steps":["trace[342910281] 'read index received'  (duration: 32.601µs)","trace[342910281] 'applied index is now lower than readState.Index'  (duration: 163.878554ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:13:18.774776Z","caller":"traceutil/trace.go:171","msg":"trace[39766679] transaction","detail":"{read_only:false; response_revision:18612; number_of_response:1; }","duration":"205.771632ms","start":"2026-09-01T19:13:18.568988Z","end":"2026-09-01T19:13:18.774759Z","steps":["trace[39766679] 'process raft request'  (duration: 38.115658ms)","trace[39766679] 'compare'  (duration: 167.192601ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:13:18.775068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.440665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-01T19:13:18.775118Z","caller":"traceutil/trace.go:171","msg":"trace[1261950476] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:18612; }","duration":"131.496537ms","start":"2026-09-01T19:13:18.643607Z","end":"2026-09-01T19:13:18.775103Z","steps":["trace[1261950476] 'agreement among raft nodes before linearized reading'  (duration: 131.375593ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:13:18.775281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.568552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pflwm\" ","response":"range_response_count:1 size:22126"}
{"level":"info","ts":"2026-09-01T19:13:18.775332Z","caller":"traceutil/trace.go:171","msg":"trace[1489760046] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pflwm; range_end:; response_count:1; response_revision:18612; }","duration":"164.652824ms","start":"2026-09-01T19:13:18.610665Z","end":"2026-09-01T19:13:18.775318Z","steps":["trace[1489760046] 'agreement among raft nodes before linearized reading'  (duration: 164.47334ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:13:19.145129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.164981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-8sfwg\" ","response":"range_response_count:1 size:9800"}
{"level":"info","ts":"2026-09-01T19:13:19.145326Z","caller":"traceutil/trace.go:171","msg":"trace[979552653] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-8sfwg; range_end:; response_count:1; response_revision:18612; }","duration":"102.420888ms","start":"2026-09-01T19:13:19.042884Z","end":"2026-09-01T19:13:19.145305Z","steps":["trace[979552653] 'range keys from in-memory index tree'  (duration: 101.884643ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:13:19.463964Z","caller":"traceutil/trace.go:171","msg":"trace[2090599227] transaction","detail":"{read_only:false; response_revision:18614; number_of_response:1; }","duration":"299.997493ms","start":"2026-09-01T19:13:19.163943Z","end":"2026-09-01T19:13:19.463941Z","steps":["trace[2090599227] 'process raft request'  (duration: 299.364856ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:13:19.4641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:13:19.163918Z","time spent":"300.106676ms","remote":"127.0.0.1:58076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c426c4dd-5878-4226-8321-4c5b6f1b5835\" mod_revision:18592 > success:<request_put:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c426c4dd-5878-4226-8321-4c5b6f1b5835\" value_size:3642 >> failure:<request_range:<key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c426c4dd-5878-4226-8321-4c5b6f1b5835\" > >"}
{"level":"info","ts":"2026-09-01T19:13:19.464528Z","caller":"traceutil/trace.go:171","msg":"trace[133247029] linearizableReadLoop","detail":"{readStateIndex:19253; appliedIndex:19254; }","duration":"124.667946ms","start":"2026-09-01T19:13:19.339833Z","end":"2026-09-01T19:13:19.4645Z","steps":["trace[133247029] 'read index received'  (duration: 124.657046ms)","trace[133247029] 'applied index is now lower than readState.Index'  (duration: 8.72µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:13:19.464763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.910294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"}
{"level":"info","ts":"2026-09-01T19:13:19.464812Z","caller":"traceutil/trace.go:171","msg":"trace[1522464566] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:18615; }","duration":"125.010915ms","start":"2026-09-01T19:13:19.33979Z","end":"2026-09-01T19:13:19.464801Z","steps":["trace[1522464566] 'agreement among raft nodes before linearized reading'  (duration: 124.80429ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:14:24.525417Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dd65ba9af29ebfc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-01T19:14:24.532134Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-09-01T19:14:24.532242Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-09-01T19:15:18.673205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17218}
{"level":"info","ts":"2026-09-01T19:15:18.780725Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17218,"took":"104.758471ms","hash":3338467244,"current-db-size-bytes":47398912,"current-db-size":"47 MB","current-db-size-in-use-bytes":34033664,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-09-01T19:15:18.780792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3338467244,"revision":17218,"compact-revision":14632}
{"level":"info","ts":"2026-09-01T19:18:50.8956Z","caller":"traceutil/trace.go:171","msg":"trace[120748543] transaction","detail":"{read_only:false; response_revision:21160; number_of_response:1; }","duration":"149.259008ms","start":"2026-09-01T19:18:50.746304Z","end":"2026-09-01T19:18:50.895563Z","steps":["trace[120748543] 'process raft request'  (duration: 148.94117ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:51.073932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.060142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-40310f5c-614c-47a8-8b40-b7d9a2bd6d95\" ","response":"range_response_count:1 size:1319"}
{"level":"info","ts":"2026-09-01T19:18:51.074056Z","caller":"traceutil/trace.go:171","msg":"trace[1139207344] range","detail":"{range_begin:/registry/persistentvolumes/pvc-40310f5c-614c-47a8-8b40-b7d9a2bd6d95; range_end:; response_count:1; response_revision:21160; }","duration":"175.239847ms","start":"2026-09-01T19:18:50.898787Z","end":"2026-09-01T19:18:51.074027Z","steps":["trace[1139207344] 'range keys from in-memory index tree'  (duration: 174.819716ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:51.07409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.157871ms","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-09-01T19:18:51.0742Z","caller":"traceutil/trace.go:171","msg":"trace[1227868852] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:21160; }","duration":"136.337186ms","start":"2026-09-01T19:18:50.937832Z","end":"2026-09-01T19:18:51.074169Z","steps":["trace[1227868852] 'range keys from in-memory index tree'  (duration: 135.926485ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:51.252407Z","caller":"traceutil/trace.go:171","msg":"trace[1052200478] transaction","detail":"{read_only:false; response_revision:21161; number_of_response:1; }","duration":"170.418739ms","start":"2026-09-01T19:18:51.081959Z","end":"2026-09-01T19:18:51.252377Z","steps":["trace[1052200478] 'process raft request'  (duration: 170.243585ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:51.78348Z","caller":"traceutil/trace.go:171","msg":"trace[1040429086] transaction","detail":"{read_only:false; response_revision:21162; number_of_response:1; }","duration":"199.981853ms","start":"2026-09-01T19:18:51.58348Z","end":"2026-09-01T19:18:51.783462Z","steps":["trace[1040429086] 'process raft request'  (duration: 199.794128ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:51.783666Z","caller":"traceutil/trace.go:171","msg":"trace[201204361] linearizableReadLoop","detail":"{readStateIndex:21886; appliedIndex:21886; }","duration":"143.606569ms","start":"2026-09-01T19:18:51.640011Z","end":"2026-09-01T19:18:51.783617Z","steps":["trace[201204361] 'read index received'  (duration: 143.579608ms)","trace[201204361] 'applied index is now lower than readState.Index'  (duration: 22.511µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:18:51.783885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.865395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-09-01T19:18:51.783954Z","caller":"traceutil/trace.go:171","msg":"trace[231989286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21162; }","duration":"143.976188ms","start":"2026-09-01T19:18:51.639961Z","end":"2026-09-01T19:18:51.783938Z","steps":["trace[231989286] 'agreement among raft nodes before linearized reading'  (duration: 143.796844ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:52.083364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.490641ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242240088 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:21154 > 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-09-01T19:18:52.083586Z","caller":"traceutil/trace.go:171","msg":"trace[1721705331] transaction","detail":"{read_only:false; response_revision:21163; number_of_response:1; }","duration":"295.919386ms","start":"2026-09-01T19:18:51.78762Z","end":"2026-09-01T19:18:52.083539Z","steps":["trace[1721705331] 'process raft request'  (duration: 157.134747ms)","trace[1721705331] 'compare'  (duration: 138.339337ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:18:52.083767Z","caller":"traceutil/trace.go:171","msg":"trace[660931741] linearizableReadLoop","detail":"{readStateIndex:21889; appliedIndex:21886; }","duration":"226.243089ms","start":"2026-09-01T19:18:51.8575Z","end":"2026-09-01T19:18:52.083743Z","steps":["trace[660931741] 'read index received'  (duration: 87.183512ms)","trace[660931741] 'applied index is now lower than readState.Index'  (duration: 139.057867ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:18:52.083878Z","caller":"traceutil/trace.go:171","msg":"trace[248237662] transaction","detail":"{read_only:false; response_revision:21165; number_of_response:1; }","duration":"295.830094ms","start":"2026-09-01T19:18:51.78804Z","end":"2026-09-01T19:18:52.08387Z","steps":["trace[248237662] 'process raft request'  (duration: 295.588177ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:52.083929Z","caller":"traceutil/trace.go:171","msg":"trace[1681626711] transaction","detail":"{read_only:false; response_revision:21164; number_of_response:1; }","duration":"295.935936ms","start":"2026-09-01T19:18:51.787915Z","end":"2026-09-01T19:18:52.083851Z","steps":["trace[1681626711] 'process raft request'  (duration: 295.601818ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:52.084017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.240597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"warn","ts":"2026-09-01T19:18:52.084063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.565448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"}
{"level":"info","ts":"2026-09-01T19:18:52.084128Z","caller":"traceutil/trace.go:171","msg":"trace[313194114] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21165; }","duration":"186.377982ms","start":"2026-09-01T19:18:51.897708Z","end":"2026-09-01T19:18:52.084086Z","steps":["trace[313194114] 'agreement among raft nodes before linearized reading'  (duration: 186.206587ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:52.084211Z","caller":"traceutil/trace.go:171","msg":"trace[1811409390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21165; }","duration":"226.741752ms","start":"2026-09-01T19:18:51.857408Z","end":"2026-09-01T19:18:52.08415Z","steps":["trace[1811409390] 'agreement among raft nodes before linearized reading'  (duration: 226.475385ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:52.272589Z","caller":"traceutil/trace.go:171","msg":"trace[1425836922] transaction","detail":"{read_only:false; response_revision:21166; number_of_response:1; }","duration":"184.251016ms","start":"2026-09-01T19:18:52.088293Z","end":"2026-09-01T19:18:52.272544Z","steps":["trace[1425836922] 'process raft request'  (duration: 121.292966ms)","trace[1425836922] 'compare'  (duration: 62.552549ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:18:52.353338Z","caller":"traceutil/trace.go:171","msg":"trace[474587106] linearizableReadLoop","detail":"{readStateIndex:21892; appliedIndex:21889; }","duration":"208.776896ms","start":"2026-09-01T19:18:52.144537Z","end":"2026-09-01T19:18:52.353314Z","steps":["trace[474587106] 'read index received'  (duration: 65.070265ms)","trace[474587106] 'applied index is now lower than readState.Index'  (duration: 143.705451ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T19:18:52.353414Z","caller":"traceutil/trace.go:171","msg":"trace[1039027092] transaction","detail":"{read_only:false; response_revision:21167; number_of_response:1; }","duration":"264.089283ms","start":"2026-09-01T19:18:52.089299Z","end":"2026-09-01T19:18:52.353388Z","steps":["trace[1039027092] 'process raft request'  (duration: 263.830856ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:52.353469Z","caller":"traceutil/trace.go:171","msg":"trace[930105662] transaction","detail":"{read_only:false; response_revision:21168; number_of_response:1; }","duration":"262.056879ms","start":"2026-09-01T19:18:52.091377Z","end":"2026-09-01T19:18:52.353434Z","steps":["trace[930105662] 'process raft request'  (duration: 261.898485ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:52.353814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.273639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-01T19:18:52.35386Z","caller":"traceutil/trace.go:171","msg":"trace[212949047] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:21168; }","duration":"209.431303ms","start":"2026-09-01T19:18:52.14442Z","end":"2026-09-01T19:18:52.353851Z","steps":["trace[212949047] 'agreement among raft nodes before linearized reading'  (duration: 209.254849ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:52.353903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.290876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.123\" ","response":"range_response_count:1 size:140"}
{"level":"info","ts":"2026-09-01T19:18:52.353955Z","caller":"traceutil/trace.go:171","msg":"trace[607363034] range","detail":"{range_begin:/registry/masterleases/162.253.55.123; range_end:; response_count:1; response_revision:21168; }","duration":"147.395479ms","start":"2026-09-01T19:18:52.206546Z","end":"2026-09-01T19:18:52.353942Z","steps":["trace[607363034] 'agreement among raft nodes before linearized reading'  (duration: 147.218314ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:52.817121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.108303ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753258242240101 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:21159 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-01T19:18:52.817307Z","caller":"traceutil/trace.go:171","msg":"trace[1991731742] transaction","detail":"{read_only:false; response_revision:21171; number_of_response:1; }","duration":"318.867206ms","start":"2026-09-01T19:18:52.498422Z","end":"2026-09-01T19:18:52.81729Z","steps":["trace[1991731742] 'process raft request'  (duration: 318.798834ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:18:52.817309Z","caller":"traceutil/trace.go:171","msg":"trace[581254350] linearizableReadLoop","detail":"{readStateIndex:21895; appliedIndex:21894; }","duration":"318.746842ms","start":"2026-09-01T19:18:52.498536Z","end":"2026-09-01T19:18:52.817283Z","steps":["trace[581254350] 'read index received'  (duration: 143.389242ms)","trace[581254350] 'applied index is now lower than readState.Index'  (duration: 175.35607ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:18:52.81737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:18:52.4984Z","time spent":"318.938638ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/masterleases/162.253.55.123\" mod_revision:21119 > success:<request_put:<key:\"/registry/masterleases/162.253.55.123\" value_size:68 lease:4596381221387464290 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.123\" > >"}
{"level":"info","ts":"2026-09-01T19:18:52.81734Z","caller":"traceutil/trace.go:171","msg":"trace[1880613702] transaction","detail":"{read_only:false; response_revision:21170; number_of_response:1; }","duration":"340.806478ms","start":"2026-09-01T19:18:52.476463Z","end":"2026-09-01T19:18:52.81727Z","steps":["trace[1880613702] 'process raft request'  (duration: 165.462938ms)","trace[1880613702] 'compare'  (duration: 174.927169ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T19:18:52.817513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.964178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-01T19:18:52.8176Z","caller":"traceutil/trace.go:171","msg":"trace[76611350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21171; }","duration":"319.090821ms","start":"2026-09-01T19:18:52.49849Z","end":"2026-09-01T19:18:52.81758Z","steps":["trace[76611350] 'agreement among raft nodes before linearized reading'  (duration: 318.870805ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:18:52.817642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:18:52.498474Z","time spent":"319.160514ms","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-01T19:18:52.817727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:18:52.47641Z","time spent":"341.133036ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:21159 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-01T19:20:18.685298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19716}
{"level":"info","ts":"2026-09-01T19:20:18.804659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19716,"took":"116.1511ms","hash":3317606487,"current-db-size-bytes":48242688,"current-db-size":"48 MB","current-db-size-in-use-bytes":29782016,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-01T19:20:18.804719Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3317606487,"revision":19716,"compact-revision":17218}
{"level":"info","ts":"2026-09-01T19:22:42.891924Z","caller":"traceutil/trace.go:171","msg":"trace[78473103] transaction","detail":"{read_only:false; response_revision:23605; number_of_response:1; }","duration":"303.832927ms","start":"2026-09-01T19:22:42.588067Z","end":"2026-09-01T19:22:42.8919Z","steps":["trace[78473103] 'process raft request'  (duration: 303.177179ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T19:22:42.892118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T19:22:42.588038Z","time spent":"303.982151ms","remote":"127.0.0.1:58160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:23562 > 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":"info","ts":"2026-09-01T19:25:18.693348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21938}
{"level":"info","ts":"2026-09-01T19:25:18.822641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21938,"took":"125.376808ms","hash":3894987992,"current-db-size-bytes":52678656,"current-db-size":"53 MB","current-db-size-in-use-bytes":44220416,"current-db-size-in-use":"44 MB"}
{"level":"info","ts":"2026-09-01T19:25:18.822866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3894987992,"revision":21938,"compact-revision":19716}
{"level":"info","ts":"2026-09-01T19:27:46.183228Z","caller":"traceutil/trace.go:171","msg":"trace[1119748028] transaction","detail":"{read_only:false; response_revision:26152; number_of_response:1; }","duration":"120.046247ms","start":"2026-09-01T19:27:46.063143Z","end":"2026-09-01T19:27:46.18319Z","steps":["trace[1119748028] 'process raft request'  (duration: 119.799401ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T19:30:18.705494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25078}
{"level":"info","ts":"2026-09-01T19:30:18.86952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25078,"took":"159.656752ms","hash":316589823,"current-db-size-bytes":52678656,"current-db-size":"53 MB","current-db-size-in-use-bytes":30523392,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-01T19:30:18.869605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":316589823,"revision":25078,"compact-revision":21938}