{"level":"warn","ts":"2026-09-17T18:36:35.579073Z","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-17T18:36:35.579185Z","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-17T18:36:35.579364Z","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-17T18:36:35.579381Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.123:2380"]}
{"level":"info","ts":"2026-09-17T18:36:35.579442Z","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-17T18:36:35.580269Z","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-17T18:36:35.580346Z","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":8,"max-cpu-available":8,"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-17T18:36:35.585878Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.269748ms"}
{"level":"info","ts":"2026-09-17T18:36:35.592182Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dd65ba9af29ebfc9","cluster-id":"67ca29879725fb76"}
{"level":"info","ts":"2026-09-17T18:36:35.592316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=()"}
{"level":"info","ts":"2026-09-17T18:36:35.592351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 0"}
{"level":"info","ts":"2026-09-17T18:36:35.592372Z","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-17T18:36:35.59239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became follower at term 1"}
{"level":"info","ts":"2026-09-17T18:36:35.592448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"}
{"level":"warn","ts":"2026-09-17T18:36:35.599095Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-17T18:36:35.603201Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-17T18:36:35.604426Z","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-17T18:36:35.606484Z","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-17T18:36:35.607081Z","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-17T18:36:35.607131Z","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-17T18:36:35.610272Z","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-17T18:36:35.610481Z","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-17T18:36:35.61073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 switched to configuration voters=(15953362429688070089)"}
{"level":"info","ts":"2026-09-17T18:36:35.611531Z","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-17T18:36:35.614412Z","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-17T18:36:35.614855Z","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-17T18:36:35.614911Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.123:2380"}
{"level":"info","ts":"2026-09-17T18:36:35.614911Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-17T18:36:35.614964Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.123:2380"}
{"level":"info","ts":"2026-09-17T18:36:35.694955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 is starting a new election at term 1"}
{"level":"info","ts":"2026-09-17T18:36:35.695198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-17T18:36:35.695281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgPreVoteResp from dd65ba9af29ebfc9 at term 1"}
{"level":"info","ts":"2026-09-17T18:36:35.695347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became candidate at term 2"}
{"level":"info","ts":"2026-09-17T18:36:35.695389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 received MsgVoteResp from dd65ba9af29ebfc9 at term 2"}
{"level":"info","ts":"2026-09-17T18:36:35.695497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dd65ba9af29ebfc9 became leader at term 2"}
{"level":"info","ts":"2026-09-17T18:36:35.69556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dd65ba9af29ebfc9 elected leader dd65ba9af29ebfc9 at term 2"}
{"level":"info","ts":"2026-09-17T18:36:35.696829Z","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-17T18:36:35.69691Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-17T18:36:35.69715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-17T18:36:35.697943Z","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-17T18:36:35.698068Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-17T18:36:35.698104Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-17T18:36:35.698123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-17T18:36:35.698422Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-17T18:36:35.698568Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-17T18:36:35.700574Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-17T18:36:35.702015Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.123:2379"}
{"level":"warn","ts":"2026-09-17T18:36:39.332907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.82752ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753612156439018 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" value_size:646 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-09-17T18:36:39.33304Z","caller":"traceutil/trace.go:171","msg":"trace[1936210521] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"279.806796ms","start":"2026-09-17T18:36:39.053214Z","end":"2026-09-17T18:36:39.333021Z","steps":["trace[1936210521] 'compare'  (duration: 270.649046ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:36:40.397138Z","caller":"traceutil/trace.go:171","msg":"trace[1240189235] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"159.282364ms","start":"2026-09-17T18:36:40.237818Z","end":"2026-09-17T18:36:40.397101Z","steps":["trace[1240189235] 'process raft request'  (duration: 159.10296ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:36:40.706621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.738948ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753612156439094 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/secrets/kube-system/bootstrap-token-6204qi\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/bootstrap-token-6204qi\" value_size:465 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-09-17T18:36:40.706754Z","caller":"traceutil/trace.go:171","msg":"trace[1776237596] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:252; }","duration":"183.582107ms","start":"2026-09-17T18:36:40.523151Z","end":"2026-09-17T18:36:40.706733Z","steps":["trace[1776237596] 'read index received'  (duration: 32.595069ms)","trace[1776237596] 'applied index is now lower than readState.Index'  (duration: 150.985748ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:36:40.70686Z","caller":"traceutil/trace.go:171","msg":"trace[1596755963] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"300.259532ms","start":"2026-09-17T18:36:40.40655Z","end":"2026-09-17T18:36:40.706809Z","steps":["trace[1596755963] 'process raft request'  (duration: 149.238093ms)","trace[1596755963] 'compare'  (duration: 150.546183ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:36:40.706948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.76213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:36:40.707287Z","caller":"traceutil/trace.go:171","msg":"trace[411220777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:247; }","duration":"184.181272ms","start":"2026-09-17T18:36:40.523083Z","end":"2026-09-17T18:36:40.707265Z","steps":["trace[411220777] 'agreement among raft nodes before linearized reading'  (duration: 183.738518ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:36:40.707305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:36:40.406526Z","time spent":"300.575657ms","remote":"127.0.0.1:59106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/kube-system/bootstrap-token-6204qi\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/bootstrap-token-6204qi\" value_size:465 >> failure:<>"}
{"level":"info","ts":"2026-09-17T18:37:29.636705Z","caller":"traceutil/trace.go:171","msg":"trace[1527658629] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"166.693597ms","start":"2026-09-17T18:37:29.469986Z","end":"2026-09-17T18:37:29.636679Z","steps":["trace[1527658629] 'read index received'  (duration: 165.803544ms)","trace[1527658629] 'applied index is now lower than readState.Index'  (duration: 888.973µs)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:37:29.636787Z","caller":"traceutil/trace.go:171","msg":"trace[35171926] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"312.928163ms","start":"2026-09-17T18:37:29.323825Z","end":"2026-09-17T18:37:29.636753Z","steps":["trace[35171926] 'process raft request'  (duration: 312.200774ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:29.636998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.975921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-09-17T18:37:29.637076Z","caller":"traceutil/trace.go:171","msg":"trace[187634183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"167.104251ms","start":"2026-09-17T18:37:29.469945Z","end":"2026-09-17T18:37:29.637049Z","steps":["trace[187634183] 'agreement among raft nodes before linearized reading'  (duration: 166.879402ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:29.63705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.842766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-17T18:37:29.637154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:29.323794Z","time spent":"313.065945ms","remote":"127.0.0.1:59106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:645 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036177 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"info","ts":"2026-09-17T18:37:29.637182Z","caller":"traceutil/trace.go:171","msg":"trace[138407513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"146.054533ms","start":"2026-09-17T18:37:29.491084Z","end":"2026-09-17T18:37:29.637139Z","steps":["trace[138407513] 'agreement among raft nodes before linearized reading'  (duration: 145.812102ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:30.057775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.545805ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753612156439920 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:3fc9a0b0a8018d6f>","response":"size:40"}
{"level":"info","ts":"2026-09-17T18:37:30.058077Z","caller":"traceutil/trace.go:171","msg":"trace[2063241115] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"336.385494ms","start":"2026-09-17T18:37:29.721678Z","end":"2026-09-17T18:37:30.058063Z","steps":["trace[2063241115] 'process raft request'  (duration: 336.349491ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:30.058141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:29.721653Z","time spent":"336.449219ms","remote":"127.0.0.1:59352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" value_size:994 >> failure:<>"}
{"level":"info","ts":"2026-09-17T18:37:30.058282Z","caller":"traceutil/trace.go:171","msg":"trace[349253270] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"416.916694ms","start":"2026-09-17T18:37:29.64136Z","end":"2026-09-17T18:37:30.058277Z","steps":["trace[349253270] 'process raft request'  (duration: 416.630461ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:30.058326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:29.641339Z","time spent":"416.958957ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:637 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-17T18:37:30.058401Z","caller":"traceutil/trace.go:171","msg":"trace[1569286368] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"416.99301ms","start":"2026-09-17T18:37:29.6413Z","end":"2026-09-17T18:37:30.058293Z","steps":["trace[1569286368] 'process raft request'  (duration: 416.593327ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:30.058567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.254129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:37:30.058588Z","caller":"traceutil/trace.go:171","msg":"trace[1310809562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"419.324505ms","start":"2026-09-17T18:37:29.639259Z","end":"2026-09-17T18:37:30.058584Z","steps":["trace[1310809562] 'agreement among raft nodes before linearized reading'  (duration: 419.241128ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:30.058605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:29.639232Z","time spent":"419.367908ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-09-17T18:37:30.058487Z","caller":"traceutil/trace.go:171","msg":"trace[1775405759] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"419.138509ms","start":"2026-09-17T18:37:29.639341Z","end":"2026-09-17T18:37:30.058479Z","steps":["trace[1775405759] 'read index received'  (duration: 86.880265ms)","trace[1775405759] 'applied index is now lower than readState.Index'  (duration: 332.257244ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:37:30.058699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:29.639115Z","time spent":"419.494388ms","remote":"127.0.0.1:59030","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-09-17T18:37:30.058799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:29.641286Z","time spent":"417.230331ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:638 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-09-17T18:37:30.058981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.390548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-g9b22\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-09-17T18:37:30.059032Z","caller":"traceutil/trace.go:171","msg":"trace[1888027926] range","detail":"{range_begin:/registry/pods/kube-system/cilium-g9b22; range_end:; response_count:1; response_revision:654; }","duration":"148.502867ms","start":"2026-09-17T18:37:29.910512Z","end":"2026-09-17T18:37:30.059015Z","steps":["trace[1888027926] 'agreement among raft nodes before linearized reading'  (duration: 148.361635ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:31.212139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04531032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-09-17T18:37:31.212223Z","caller":"traceutil/trace.go:171","msg":"trace[994620521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"1.045453842s","start":"2026-09-17T18:37:30.166749Z","end":"2026-09-17T18:37:31.212202Z","steps":["trace[994620521] 'range keys from in-memory index tree'  (duration: 1.045143186s)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:31.212253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:30.166732Z","time spent":"1.045515607s","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-09-17T18:37:31.253505Z","caller":"traceutil/trace.go:171","msg":"trace[2013834961] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"154.754276ms","start":"2026-09-17T18:37:31.098727Z","end":"2026-09-17T18:37:31.253481Z","steps":["trace[2013834961] 'read index received'  (duration: 153.652235ms)","trace[2013834961] 'applied index is now lower than readState.Index'  (duration: 1.100771ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:37:31.253624Z","caller":"traceutil/trace.go:171","msg":"trace[2142286455] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"785.619672ms","start":"2026-09-17T18:37:30.467949Z","end":"2026-09-17T18:37:31.253569Z","steps":["trace[2142286455] 'process raft request'  (duration: 784.768832ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:31.253788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.038879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-09-17T18:37:31.253852Z","caller":"traceutil/trace.go:171","msg":"trace[597718035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"155.16033ms","start":"2026-09-17T18:37:31.098681Z","end":"2026-09-17T18:37:31.253841Z","steps":["trace[597718035] 'agreement among raft nodes before linearized reading'  (duration: 154.909399ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:31.253928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:30.467924Z","time spent":"785.843421ms","remote":"127.0.0.1:59106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:651 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036209 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"info","ts":"2026-09-17T18:37:47.5836Z","caller":"traceutil/trace.go:171","msg":"trace[2014516931] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"248.13164ms","start":"2026-09-17T18:37:47.335441Z","end":"2026-09-17T18:37:47.583573Z","steps":["trace[2014516931] 'process raft request'  (duration: 247.952561ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:37:48.04651Z","caller":"traceutil/trace.go:171","msg":"trace[305402262] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:858; }","duration":"557.398384ms","start":"2026-09-17T18:37:47.489077Z","end":"2026-09-17T18:37:48.046476Z","steps":["trace[305402262] 'read index received'  (duration: 94.421711ms)","trace[305402262] 'applied index is now lower than readState.Index'  (duration: 462.975063ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:37:48.046711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.606971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:37:48.046674Z","caller":"traceutil/trace.go:171","msg":"trace[1659709316] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"709.466994ms","start":"2026-09-17T18:37:47.337165Z","end":"2026-09-17T18:37:48.046632Z","steps":["trace[1659709316] 'process raft request'  (duration: 613.663441ms)","trace[1659709316] 'compare'  (duration: 95.32278ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:37:48.046757Z","caller":"traceutil/trace.go:171","msg":"trace[1885358716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"557.698646ms","start":"2026-09-17T18:37:47.489047Z","end":"2026-09-17T18:37:48.046745Z","steps":["trace[1885358716] 'agreement among raft nodes before linearized reading'  (duration: 557.555654ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:37:48.046819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:47.489036Z","time spent":"557.774601ms","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-17T18:37:48.046891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:37:47.337148Z","time spent":"709.645453ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:811 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-09-17T18:38:21.024759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.405146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-09-17T18:38:21.024889Z","caller":"traceutil/trace.go:171","msg":"trace[767301267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"572.586064ms","start":"2026-09-17T18:38:20.452271Z","end":"2026-09-17T18:38:21.024858Z","steps":["trace[767301267] 'range keys from in-memory index tree'  (duration: 572.171597ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:38:21.024958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:38:20.452254Z","time spent":"572.677314ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-17T18:38:21.024996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.146873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:38:21.025058Z","caller":"traceutil/trace.go:171","msg":"trace[168382487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"535.246832ms","start":"2026-09-17T18:38:20.489794Z","end":"2026-09-17T18:38:21.025041Z","steps":["trace[168382487] 'range keys from in-memory index tree'  (duration: 535.073564ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:38:21.025096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:38:20.489785Z","time spent":"535.300291ms","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-17T18:38:21.025298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.776063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-09-17T18:38:21.025388Z","caller":"traceutil/trace.go:171","msg":"trace[1296367650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"572.937502ms","start":"2026-09-17T18:38:20.452427Z","end":"2026-09-17T18:38:21.025364Z","steps":["trace[1296367650] 'range keys from in-memory index tree'  (duration: 572.522665ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:38:21.025438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:38:20.452383Z","time spent":"573.04323ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-09-17T18:38:46.709909Z","caller":"traceutil/trace.go:171","msg":"trace[582698106] linearizableReadLoop","detail":"{readStateIndex:1558; appliedIndex:1557; }","duration":"133.544243ms","start":"2026-09-17T18:38:46.57634Z","end":"2026-09-17T18:38:46.709884Z","steps":["trace[582698106] 'read index received'  (duration: 133.400731ms)","trace[582698106] 'applied index is now lower than readState.Index'  (duration: 142.092µs)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:38:46.710307Z","caller":"traceutil/trace.go:171","msg":"trace[1832943515] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"240.746071ms","start":"2026-09-17T18:38:46.469527Z","end":"2026-09-17T18:38:46.710273Z","steps":["trace[1832943515] 'process raft request'  (duration: 240.192152ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:38:46.710583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.214704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:38:46.710635Z","caller":"traceutil/trace.go:171","msg":"trace[725374453] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1505; }","duration":"134.307775ms","start":"2026-09-17T18:38:46.576314Z","end":"2026-09-17T18:38:46.710622Z","steps":["trace[725374453] 'agreement among raft nodes before linearized reading'  (duration: 134.207063ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:38:46.710997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.72755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-17T18:38:46.711052Z","caller":"traceutil/trace.go:171","msg":"trace[1676758855] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1505; }","duration":"104.823192ms","start":"2026-09-17T18:38:46.606217Z","end":"2026-09-17T18:38:46.71104Z","steps":["trace[1676758855] 'agreement among raft nodes before linearized reading'  (duration: 104.526767ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:14.184658Z","caller":"traceutil/trace.go:171","msg":"trace[228865242] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"154.060033ms","start":"2026-09-17T18:39:14.030574Z","end":"2026-09-17T18:39:14.184634Z","steps":["trace[228865242] 'read index received'  (duration: 153.856496ms)","trace[228865242] 'applied index is now lower than readState.Index'  (duration: 202.257µs)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:14.184735Z","caller":"traceutil/trace.go:171","msg":"trace[1535631287] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"262.744491ms","start":"2026-09-17T18:39:13.921956Z","end":"2026-09-17T18:39:14.184701Z","steps":["trace[1535631287] 'process raft request'  (duration: 262.501213ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:14.184902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.310681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-53813997-0aa1-4e01-beb7-2c629658ba4f\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-09-17T18:39:14.184962Z","caller":"traceutil/trace.go:171","msg":"trace[502147932] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-53813997-0aa1-4e01-beb7-2c629658ba4f; range_end:; response_count:1; response_revision:1928; }","duration":"154.413765ms","start":"2026-09-17T18:39:14.030522Z","end":"2026-09-17T18:39:14.184936Z","steps":["trace[502147932] 'agreement among raft nodes before linearized reading'  (duration: 154.252398ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:14.273978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.348714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-17T18:39:14.274026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.394903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-09-17T18:39:14.274083Z","caller":"traceutil/trace.go:171","msg":"trace[1556437127] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:1929; }","duration":"203.495059ms","start":"2026-09-17T18:39:14.070557Z","end":"2026-09-17T18:39:14.274052Z","steps":["trace[1556437127] 'agreement among raft nodes before linearized reading'  (duration: 203.297833ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:14.274095Z","caller":"traceutil/trace.go:171","msg":"trace[2015219286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1929; }","duration":"109.524767ms","start":"2026-09-17T18:39:14.164553Z","end":"2026-09-17T18:39:14.274078Z","steps":["trace[2015219286] 'agreement among raft nodes before linearized reading'  (duration: 109.326511ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:14.274213Z","caller":"traceutil/trace.go:171","msg":"trace[856746217] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"218.981472ms","start":"2026-09-17T18:39:14.054858Z","end":"2026-09-17T18:39:14.273839Z","steps":["trace[856746217] 'process raft request'  (duration: 180.572871ms)","trace[856746217] 'compare'  (duration: 38.192974ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:39:14.562742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.775733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-09-17T18:39:14.562857Z","caller":"traceutil/trace.go:171","msg":"trace[331770057] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1930; }","duration":"182.952708ms","start":"2026-09-17T18:39:14.379884Z","end":"2026-09-17T18:39:14.562837Z","steps":["trace[331770057] 'range keys from in-memory index tree'  (duration: 182.580837ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:21.847559Z","caller":"traceutil/trace.go:171","msg":"trace[1453925748] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"158.736017ms","start":"2026-09-17T18:39:21.688783Z","end":"2026-09-17T18:39:21.847519Z","steps":["trace[1453925748] 'process raft request'  (duration: 158.431587ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:23.082147Z","caller":"traceutil/trace.go:171","msg":"trace[2090888481] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"141.29213ms","start":"2026-09-17T18:39:22.940832Z","end":"2026-09-17T18:39:23.082124Z","steps":["trace[2090888481] 'process raft request'  (duration: 126.595541ms)","trace[2090888481] 'compare'  (duration: 14.534693ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:23.082374Z","caller":"traceutil/trace.go:171","msg":"trace[1640118562] transaction","detail":"{read_only:false; response_revision:2015; number_of_response:1; }","duration":"130.352506ms","start":"2026-09-17T18:39:22.951969Z","end":"2026-09-17T18:39:23.082321Z","steps":["trace[1640118562] 'process raft request'  (duration: 130.115507ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:27.826004Z","caller":"traceutil/trace.go:171","msg":"trace[1702381801] linearizableReadLoop","detail":"{readStateIndex:2129; appliedIndex:2128; }","duration":"194.869869ms","start":"2026-09-17T18:39:27.631106Z","end":"2026-09-17T18:39:27.825976Z","steps":["trace[1702381801] 'read index received'  (duration: 177.584934ms)","trace[1702381801] 'applied index is now lower than readState.Index'  (duration: 17.283815ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:27.826039Z","caller":"traceutil/trace.go:171","msg":"trace[60648969] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"200.035711ms","start":"2026-09-17T18:39:27.625976Z","end":"2026-09-17T18:39:27.826012Z","steps":["trace[60648969] 'process raft request'  (duration: 183.016807ms)","trace[60648969] 'compare'  (duration: 16.860928ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:39:27.82634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.127877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-09-17T18:39:27.82641Z","caller":"traceutil/trace.go:171","msg":"trace[1009337918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2047; }","duration":"134.265443ms","start":"2026-09-17T18:39:27.692129Z","end":"2026-09-17T18:39:27.826394Z","steps":["trace[1009337918] 'agreement among raft nodes before linearized reading'  (duration: 134.074835ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:27.826692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.546156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-7xfxn\" ","response":"range_response_count:1 size:5855"}
{"level":"info","ts":"2026-09-17T18:39:27.826814Z","caller":"traceutil/trace.go:171","msg":"trace[1145847111] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-7xfxn; range_end:; response_count:1; response_revision:2047; }","duration":"195.718883ms","start":"2026-09-17T18:39:27.631047Z","end":"2026-09-17T18:39:27.826766Z","steps":["trace[1145847111] 'agreement among raft nodes before linearized reading'  (duration: 195.068497ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:43.963715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.604733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-17T18:39:43.963794Z","caller":"traceutil/trace.go:171","msg":"trace[1500365387] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2153; }","duration":"141.76186ms","start":"2026-09-17T18:39:43.822013Z","end":"2026-09-17T18:39:43.963775Z","steps":["trace[1500365387] 'count revisions from in-memory index tree'  (duration: 141.513468ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:43.964166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.200118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"}
{"level":"info","ts":"2026-09-17T18:39:43.964201Z","caller":"traceutil/trace.go:171","msg":"trace[608933056] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2153; }","duration":"191.281742ms","start":"2026-09-17T18:39:43.772912Z","end":"2026-09-17T18:39:43.964193Z","steps":["trace[608933056] 'range keys from in-memory index tree'  (duration: 190.975277ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:44.264487Z","caller":"traceutil/trace.go:171","msg":"trace[251189894] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"317.627821ms","start":"2026-09-17T18:39:43.946828Z","end":"2026-09-17T18:39:44.264455Z","steps":["trace[251189894] 'read index received'  (duration: 317.374649ms)","trace[251189894] 'applied index is now lower than readState.Index'  (duration: 251.502µs)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:44.26461Z","caller":"traceutil/trace.go:171","msg":"trace[1762689005] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"380.384617ms","start":"2026-09-17T18:39:43.88419Z","end":"2026-09-17T18:39:44.264575Z","steps":["trace[1762689005] 'process raft request'  (duration: 380.081472ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:44.264824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.976087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"warn","ts":"2026-09-17T18:39:44.264825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.292959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-53813997-0aa1-4e01-beb7-2c629658ba4f\" ","response":"range_response_count:1 size:1303"}
{"level":"warn","ts":"2026-09-17T18:39:44.264839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:43.884156Z","time spent":"380.535794ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:2142 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-09-17T18:39:44.264896Z","caller":"traceutil/trace.go:171","msg":"trace[469783982] range","detail":"{range_begin:/registry/persistentvolumes/pvc-53813997-0aa1-4e01-beb7-2c629658ba4f; range_end:; response_count:1; response_revision:2154; }","duration":"297.407715ms","start":"2026-09-17T18:39:43.96747Z","end":"2026-09-17T18:39:44.264878Z","steps":["trace[469783982] 'agreement among raft nodes before linearized reading'  (duration: 297.204116ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:44.264979Z","caller":"traceutil/trace.go:171","msg":"trace[994211037] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2154; }","duration":"318.117103ms","start":"2026-09-17T18:39:43.946753Z","end":"2026-09-17T18:39:44.26487Z","steps":["trace[994211037] 'agreement among raft nodes before linearized reading'  (duration: 317.884833ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:44.265048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:43.946736Z","time spent":"318.294891ms","remote":"127.0.0.1:52176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"info","ts":"2026-09-17T18:39:49.697561Z","caller":"traceutil/trace.go:171","msg":"trace[476465283] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"125.52187ms","start":"2026-09-17T18:39:49.572007Z","end":"2026-09-17T18:39:49.697529Z","steps":["trace[476465283] 'process raft request'  (duration: 81.957696ms)","trace[476465283] 'compare'  (duration: 43.380556ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:49.697598Z","caller":"traceutil/trace.go:171","msg":"trace[1390660414] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2267; }","duration":"100.6477ms","start":"2026-09-17T18:39:49.596911Z","end":"2026-09-17T18:39:49.697559Z","steps":["trace[1390660414] 'read index received'  (duration: 40.220489ms)","trace[1390660414] 'applied index is now lower than readState.Index'  (duration: 60.42184ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:39:49.697907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.983634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:39:49.697992Z","caller":"traceutil/trace.go:171","msg":"trace[1541839347] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:2182; }","duration":"101.10237ms","start":"2026-09-17T18:39:49.596869Z","end":"2026-09-17T18:39:49.697971Z","steps":["trace[1541839347] 'agreement among raft nodes before linearized reading'  (duration: 100.953743ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:50.08722Z","caller":"traceutil/trace.go:171","msg":"trace[1530992620] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"193.449776ms","start":"2026-09-17T18:39:49.893731Z","end":"2026-09-17T18:39:50.087181Z","steps":["trace[1530992620] 'process raft request'  (duration: 193.248527ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:51.870045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.040487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:39:51.870148Z","caller":"traceutil/trace.go:171","msg":"trace[1390087388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2191; }","duration":"381.203806ms","start":"2026-09-17T18:39:51.488917Z","end":"2026-09-17T18:39:51.870121Z","steps":["trace[1390087388] 'range keys from in-memory index tree'  (duration: 380.941773ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:51.870232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:51.488889Z","time spent":"381.331501ms","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-17T18:39:52.237863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13819753612156443069,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-09-17T18:39:52.429001Z","caller":"traceutil/trace.go:171","msg":"trace[215214018] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"724.682668ms","start":"2026-09-17T18:39:51.704288Z","end":"2026-09-17T18:39:52.428971Z","steps":["trace[215214018] 'process raft request'  (duration: 724.464457ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.429551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:51.704259Z","time spent":"724.833846ms","remote":"127.0.0.1:59260","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:2181 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-09-17T18:39:52.61251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.20386ms","expected-duration":"100ms","prefix":"","request":"header:<ID:13819753612156443071 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:2143 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:599 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-17T18:39:52.612765Z","caller":"traceutil/trace.go:171","msg":"trace[75878613] linearizableReadLoop","detail":"{readStateIndex:2281; appliedIndex:2279; }","duration":"875.55918ms","start":"2026-09-17T18:39:51.737178Z","end":"2026-09-17T18:39:52.612738Z","steps":["trace[75878613] 'read index received'  (duration: 691.481498ms)","trace[75878613] 'applied index is now lower than readState.Index'  (duration: 184.076102ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:52.612886Z","caller":"traceutil/trace.go:171","msg":"trace[1691481391] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"548.504296ms","start":"2026-09-17T18:39:52.06436Z","end":"2026-09-17T18:39:52.612864Z","steps":["trace[1691481391] 'process raft request'  (duration: 429.834644ms)","trace[1691481391] 'compare'  (duration: 118.064503ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:52.612897Z","caller":"traceutil/trace.go:171","msg":"trace[1989455498] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"275.327689ms","start":"2026-09-17T18:39:52.337535Z","end":"2026-09-17T18:39:52.612863Z","steps":["trace[1989455498] 'process raft request'  (duration: 275.098918ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.612953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.064347Z","time spent":"548.57697ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:2143 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:599 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-09-17T18:39:52.613133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.956759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-09-17T18:39:52.613174Z","caller":"traceutil/trace.go:171","msg":"trace[794377074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2194; }","duration":"876.032782ms","start":"2026-09-17T18:39:51.737129Z","end":"2026-09-17T18:39:52.613161Z","steps":["trace[794377074] 'agreement among raft nodes before linearized reading'  (duration: 875.891585ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.61319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.235083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-09-17T18:39:52.613208Z","caller":"traceutil/trace.go:171","msg":"trace[504960220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2194; }","duration":"850.297316ms","start":"2026-09-17T18:39:51.762905Z","end":"2026-09-17T18:39:52.613202Z","steps":["trace[504960220] 'agreement among raft nodes before linearized reading'  (duration: 850.242113ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.613209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:51.737102Z","time spent":"876.097826ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-09-17T18:39:52.613257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.9414ms","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-17T18:39:52.613276Z","caller":"traceutil/trace.go:171","msg":"trace[370498387] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2194; }","duration":"563.972841ms","start":"2026-09-17T18:39:52.049299Z","end":"2026-09-17T18:39:52.613272Z","steps":["trace[370498387] 'agreement among raft nodes before linearized reading'  (duration: 563.933179ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.61329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.049294Z","time spent":"563.991823ms","remote":"127.0.0.1:59364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "}
{"level":"warn","ts":"2026-09-17T18:39:52.613315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.817821ms","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":"warn","ts":"2026-09-17T18:39:52.613223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:51.762876Z","time spent":"850.343147ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-17T18:39:52.613396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.288619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:39:52.613397Z","caller":"traceutil/trace.go:171","msg":"trace[1625573686] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2194; }","duration":"519.928457ms","start":"2026-09-17T18:39:52.093449Z","end":"2026-09-17T18:39:52.613377Z","steps":["trace[1625573686] 'agreement among raft nodes before linearized reading'  (duration: 519.768689ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:52.613414Z","caller":"traceutil/trace.go:171","msg":"trace[1601184415] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2194; }","duration":"591.336811ms","start":"2026-09-17T18:39:52.022072Z","end":"2026-09-17T18:39:52.613409Z","steps":["trace[1601184415] 'agreement among raft nodes before linearized reading'  (duration: 591.30061ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.613437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.022033Z","time spent":"591.398274ms","remote":"127.0.0.1:59406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "}
{"level":"warn","ts":"2026-09-17T18:39:52.613433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.093444Z","time spent":"519.981319ms","remote":"127.0.0.1:59422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "}
{"level":"warn","ts":"2026-09-17T18:39:52.613574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.597169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"warn","ts":"2026-09-17T18:39:52.613657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.118673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:39:52.613678Z","caller":"traceutil/trace.go:171","msg":"trace[2083799067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2194; }","duration":"742.150165ms","start":"2026-09-17T18:39:51.871523Z","end":"2026-09-17T18:39:52.613673Z","steps":["trace[2083799067] 'agreement among raft nodes before linearized reading'  (duration: 742.104933ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.613706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:51.871452Z","time spent":"742.250079ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-17T18:39:52.613706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.705825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d62fbaacdd92ab\" ","response":"range_response_count:1 size:846"}
{"level":"info","ts":"2026-09-17T18:39:52.613637Z","caller":"traceutil/trace.go:171","msg":"trace[666268977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2194; }","duration":"850.663312ms","start":"2026-09-17T18:39:51.762959Z","end":"2026-09-17T18:39:52.613622Z","steps":["trace[666268977] 'agreement among raft nodes before linearized reading'  (duration: 850.423761ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.613802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:51.762931Z","time spent":"850.854963ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-09-17T18:39:52.613731Z","caller":"traceutil/trace.go:171","msg":"trace[1307256611] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d62fbaacdd92ab; range_end:; response_count:1; response_revision:2194; }","duration":"324.805571ms","start":"2026-09-17T18:39:52.288915Z","end":"2026-09-17T18:39:52.613721Z","steps":["trace[1307256611] 'agreement among raft nodes before linearized reading'  (duration: 324.664644ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:52.613979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.28889Z","time spent":"325.071654ms","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d62fbaacdd92ab\" "}
{"level":"warn","ts":"2026-09-17T18:39:52.614301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.846451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-09-17T18:39:52.615673Z","caller":"traceutil/trace.go:171","msg":"trace[1969645398] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2194; }","duration":"269.444049ms","start":"2026-09-17T18:39:52.345367Z","end":"2026-09-17T18:39:52.614811Z","steps":["trace[1969645398] 'agreement among raft nodes before linearized reading'  (duration: 268.20966ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:53.000122Z","caller":"traceutil/trace.go:171","msg":"trace[600687226] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"379.187256ms","start":"2026-09-17T18:39:52.6209Z","end":"2026-09-17T18:39:53.000087Z","steps":["trace[600687226] 'process raft request'  (duration: 356.101209ms)","trace[600687226] 'compare'  (duration: 22.613345ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:39:53.00054Z","caller":"traceutil/trace.go:171","msg":"trace[519846265] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"379.161616ms","start":"2026-09-17T18:39:52.621279Z","end":"2026-09-17T18:39:53.000441Z","steps":["trace[519846265] 'process raft request'  (duration: 378.742295ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:53.000743Z","caller":"traceutil/trace.go:171","msg":"trace[1694724374] linearizableReadLoop","detail":"{readStateIndex:2287; appliedIndex:2284; }","duration":"378.173338ms","start":"2026-09-17T18:39:52.622537Z","end":"2026-09-17T18:39:53.00071Z","steps":["trace[1694724374] 'read index received'  (duration: 354.474251ms)","trace[1694724374] 'applied index is now lower than readState.Index'  (duration: 23.698057ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:39:53.000892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.350946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-17T18:39:53.000772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.621266Z","time spent":"379.361295ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:2183 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-09-17T18:39:53.001039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.391928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:39:53.001104Z","caller":"traceutil/trace.go:171","msg":"trace[708442122] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2199; }","duration":"374.476202ms","start":"2026-09-17T18:39:52.626612Z","end":"2026-09-17T18:39:53.001088Z","steps":["trace[708442122] 'agreement among raft nodes before linearized reading'  (duration: 374.375707ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:53.00112Z","caller":"traceutil/trace.go:171","msg":"trace[1573916025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2199; }","duration":"378.408379ms","start":"2026-09-17T18:39:52.622514Z","end":"2026-09-17T18:39:53.000922Z","steps":["trace[1573916025] 'agreement among raft nodes before linearized reading'  (duration: 378.322675ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:39:53.000802Z","caller":"traceutil/trace.go:171","msg":"trace[916379466] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"379.288652ms","start":"2026-09-17T18:39:52.621497Z","end":"2026-09-17T18:39:53.000786Z","steps":["trace[916379466] 'process raft request'  (duration: 378.994158ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:39:53.001154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.626601Z","time spent":"374.542026ms","remote":"127.0.0.1:59464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "}
{"level":"warn","ts":"2026-09-17T18:39:53.001217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.622501Z","time spent":"378.64264ms","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-09-17T18:39:53.001235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.62149Z","time spent":"379.6951ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:2185 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-09-17T18:39:53.000563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:39:52.620876Z","time spent":"379.514842ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:2188 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-09-17T18:41:14.220727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.917226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:41:14.220813Z","caller":"traceutil/trace.go:171","msg":"trace[2023292499] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:2729; }","duration":"420.091747ms","start":"2026-09-17T18:41:13.800704Z","end":"2026-09-17T18:41:14.220796Z","steps":["trace[2023292499] 'count revisions from in-memory index tree'  (duration: 419.802209ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:41:14.220841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:41:13.800657Z","time spent":"420.177542ms","remote":"127.0.0.1:35468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "}
{"level":"info","ts":"2026-09-17T18:41:14.227376Z","caller":"traceutil/trace.go:171","msg":"trace[1816595576] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"278.773506ms","start":"2026-09-17T18:41:13.948572Z","end":"2026-09-17T18:41:14.227346Z","steps":["trace[1816595576] 'process raft request'  (duration: 278.589215ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:41:47.314909Z","caller":"traceutil/trace.go:171","msg":"trace[1482635707] transaction","detail":"{read_only:false; response_revision:2911; number_of_response:1; }","duration":"203.867923ms","start":"2026-09-17T18:41:47.111011Z","end":"2026-09-17T18:41:47.314879Z","steps":["trace[1482635707] 'process raft request'  (duration: 203.564295ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:41:47.343286Z","caller":"traceutil/trace.go:171","msg":"trace[1659571016] linearizableReadLoop","detail":"{readStateIndex:3025; appliedIndex:3023; }","duration":"118.458164ms","start":"2026-09-17T18:41:47.224711Z","end":"2026-09-17T18:41:47.343169Z","steps":["trace[1659571016] 'read index received'  (duration: 89.961501ms)","trace[1659571016] 'applied index is now lower than readState.Index'  (duration: 28.495983ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:41:47.343413Z","caller":"traceutil/trace.go:171","msg":"trace[201804708] transaction","detail":"{read_only:false; response_revision:2912; number_of_response:1; }","duration":"158.900183ms","start":"2026-09-17T18:41:47.184492Z","end":"2026-09-17T18:41:47.343392Z","steps":["trace[201804708] 'process raft request'  (duration: 140.660888ms)","trace[201804708] 'compare'  (duration: 17.935616ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:41:47.343548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.785205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-09-17T18:41:47.343718Z","caller":"traceutil/trace.go:171","msg":"trace[988073331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2912; }","duration":"119.003769ms","start":"2026-09-17T18:41:47.224644Z","end":"2026-09-17T18:41:47.343648Z","steps":["trace[988073331] 'agreement among raft nodes before linearized reading'  (duration: 118.547711ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:41:47.731492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.286575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"}
{"level":"info","ts":"2026-09-17T18:41:47.731591Z","caller":"traceutil/trace.go:171","msg":"trace[2053525836] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2914; }","duration":"202.403823ms","start":"2026-09-17T18:41:47.529166Z","end":"2026-09-17T18:41:47.73157Z","steps":["trace[2053525836] 'range keys from in-memory index tree'  (duration: 202.102864ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:41:48.088582Z","caller":"traceutil/trace.go:171","msg":"trace[505991304] linearizableReadLoop","detail":"{readStateIndex:3029; appliedIndex:3027; }","duration":"352.518602ms","start":"2026-09-17T18:41:47.736037Z","end":"2026-09-17T18:41:48.088555Z","steps":["trace[505991304] 'read index received'  (duration: 2.783243ms)","trace[505991304] 'applied index is now lower than readState.Index'  (duration: 349.734058ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:41:48.088604Z","caller":"traceutil/trace.go:171","msg":"trace[1859916012] transaction","detail":"{read_only:false; response_revision:2916; number_of_response:1; }","duration":"382.614997ms","start":"2026-09-17T18:41:47.705962Z","end":"2026-09-17T18:41:48.088577Z","steps":["trace[1859916012] 'process raft request'  (duration: 344.491501ms)","trace[1859916012] 'compare'  (duration: 37.986146ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:41:48.088842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.780369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"}
{"level":"info","ts":"2026-09-17T18:41:48.088939Z","caller":"traceutil/trace.go:171","msg":"trace[786002064] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2916; }","duration":"352.938108ms","start":"2026-09-17T18:41:47.735989Z","end":"2026-09-17T18:41:48.088927Z","steps":["trace[786002064] 'agreement among raft nodes before linearized reading'  (duration: 352.669672ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:41:48.088975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:41:47.735971Z","time spent":"352.991922ms","remote":"127.0.0.1:59194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "}
{"level":"warn","ts":"2026-09-17T18:41:48.089114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:41:47.705924Z","time spent":"382.782338ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2904 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-17T18:41:48.089305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.896466ms","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-17T18:41:48.08937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.721191ms","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-17T18:41:48.089427Z","caller":"traceutil/trace.go:171","msg":"trace[1197579443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2916; }","duration":"328.817687ms","start":"2026-09-17T18:41:47.760597Z","end":"2026-09-17T18:41:48.089415Z","steps":["trace[1197579443] 'agreement among raft nodes before linearized reading'  (duration: 328.6947ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:41:48.089461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:41:47.760578Z","time spent":"328.874891ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-09-17T18:41:48.089547Z","caller":"traceutil/trace.go:171","msg":"trace[922147130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2916; }","duration":"340.182205ms","start":"2026-09-17T18:41:47.749221Z","end":"2026-09-17T18:41:48.089403Z","steps":["trace[922147130] 'agreement among raft nodes before linearized reading'  (duration: 339.646292ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:41:48.089463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.00963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"warn","ts":"2026-09-17T18:41:48.08968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.669113ms","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-17T18:41:48.089744Z","caller":"traceutil/trace.go:171","msg":"trace[474793545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2916; }","duration":"313.755559ms","start":"2026-09-17T18:41:47.775977Z","end":"2026-09-17T18:41:48.089733Z","steps":["trace[474793545] 'agreement among raft nodes before linearized reading'  (duration: 313.585869ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:41:48.089731Z","caller":"traceutil/trace.go:171","msg":"trace[1864141342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2916; }","duration":"321.386343ms","start":"2026-09-17T18:41:47.768304Z","end":"2026-09-17T18:41:48.08969Z","steps":["trace[1864141342] 'agreement among raft nodes before linearized reading'  (duration: 320.714082ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:41:48.089785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:41:47.775968Z","time spent":"313.811082ms","remote":"127.0.0.1:59260","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-17T18:41:48.089692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:41:47.74921Z","time spent":"340.43716ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-09-17T18:41:48.089824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:41:47.768287Z","time spent":"321.521952ms","remote":"127.0.0.1:59260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-09-17T18:46:25.744476Z","caller":"traceutil/trace.go:171","msg":"trace[1091315959] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"103.651886ms","start":"2026-09-17T18:46:25.640798Z","end":"2026-09-17T18:46:25.744449Z","steps":["trace[1091315959] 'process raft request'  (duration: 103.598784ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:25.744565Z","caller":"traceutil/trace.go:171","msg":"trace[217677750] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"107.09405ms","start":"2026-09-17T18:46:25.637431Z","end":"2026-09-17T18:46:25.744526Z","steps":["trace[217677750] 'process raft request'  (duration: 90.981338ms)","trace[217677750] 'compare'  (duration: 15.810208ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:46:32.248264Z","caller":"traceutil/trace.go:171","msg":"trace[579827349] transaction","detail":"{read_only:false; response_revision:5076; number_of_response:1; }","duration":"156.641102ms","start":"2026-09-17T18:46:32.091564Z","end":"2026-09-17T18:46:32.248205Z","steps":["trace[579827349] 'process raft request'  (duration: 111.035366ms)","trace[579827349] 'compare'  (duration: 45.26335ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-17T18:46:32.24867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.231456ms","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-17T18:46:32.248783Z","caller":"traceutil/trace.go:171","msg":"trace[350443013] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5075; }","duration":"160.178431ms","start":"2026-09-17T18:46:32.088568Z","end":"2026-09-17T18:46:32.248747Z","steps":["trace[350443013] 'count revisions from in-memory index tree'  (duration: 159.107351ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:35.856438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855}
{"level":"info","ts":"2026-09-17T18:46:35.933978Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"76.154527ms","hash":1825419035,"current-db-size-bytes":51806208,"current-db-size":"52 MB","current-db-size-in-use-bytes":26742784,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-09-17T18:46:35.934034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1825419035,"revision":2855,"compact-revision":-1}
{"level":"info","ts":"2026-09-17T18:46:38.423974Z","caller":"traceutil/trace.go:171","msg":"trace[1297516586] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"152.903792ms","start":"2026-09-17T18:46:38.27103Z","end":"2026-09-17T18:46:38.423933Z","steps":["trace[1297516586] 'process raft request'  (duration: 152.692121ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:38.581622Z","caller":"traceutil/trace.go:171","msg":"trace[796366108] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"116.084711ms","start":"2026-09-17T18:46:38.4655Z","end":"2026-09-17T18:46:38.581584Z","steps":["trace[796366108] 'process raft request'  (duration: 115.741515ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:46:39.162923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.432248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"}
{"level":"info","ts":"2026-09-17T18:46:39.163239Z","caller":"traceutil/trace.go:171","msg":"trace[870931023] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5155; }","duration":"125.818587ms","start":"2026-09-17T18:46:39.037391Z","end":"2026-09-17T18:46:39.16321Z","steps":["trace[870931023] 'range keys from in-memory index tree'  (duration: 125.06518ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:42.146592Z","caller":"traceutil/trace.go:171","msg":"trace[1016660476] linearizableReadLoop","detail":"{readStateIndex:5439; appliedIndex:5438; }","duration":"243.889479ms","start":"2026-09-17T18:46:41.902664Z","end":"2026-09-17T18:46:42.146554Z","steps":["trace[1016660476] 'read index received'  (duration: 240.582969ms)","trace[1016660476] 'applied index is now lower than readState.Index'  (duration: 3.30483ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:46:42.146672Z","caller":"traceutil/trace.go:171","msg":"trace[977979800] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"244.261587ms","start":"2026-09-17T18:46:41.902368Z","end":"2026-09-17T18:46:42.14663Z","steps":["trace[977979800] 'process raft request'  (duration: 240.553238ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:46:42.14702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.30979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"}
{"level":"info","ts":"2026-09-17T18:46:42.147074Z","caller":"traceutil/trace.go:171","msg":"trace[1582895786] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5235; }","duration":"244.406374ms","start":"2026-09-17T18:46:41.902655Z","end":"2026-09-17T18:46:42.147061Z","steps":["trace[1582895786] 'agreement among raft nodes before linearized reading'  (duration: 244.088068ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:42.148819Z","caller":"traceutil/trace.go:171","msg":"trace[1563028820] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"235.594649ms","start":"2026-09-17T18:46:41.913201Z","end":"2026-09-17T18:46:42.148795Z","steps":["trace[1563028820] 'process raft request'  (duration: 235.342887ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:42.149034Z","caller":"traceutil/trace.go:171","msg":"trace[1113900763] transaction","detail":"{read_only:false; response_revision:5237; number_of_response:1; }","duration":"205.36492ms","start":"2026-09-17T18:46:41.943658Z","end":"2026-09-17T18:46:42.149023Z","steps":["trace[1113900763] 'process raft request'  (duration: 205.077197ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:46:42.149176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.157213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"}
{"level":"info","ts":"2026-09-17T18:46:42.149242Z","caller":"traceutil/trace.go:171","msg":"trace[1810214645] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5237; }","duration":"237.245528ms","start":"2026-09-17T18:46:41.911981Z","end":"2026-09-17T18:46:42.149227Z","steps":["trace[1810214645] 'agreement among raft nodes before linearized reading'  (duration: 237.013857ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:46:42.150693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.519501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"}
{"level":"info","ts":"2026-09-17T18:46:42.150832Z","caller":"traceutil/trace.go:171","msg":"trace[1692489068] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5237; }","duration":"115.673199ms","start":"2026-09-17T18:46:42.035091Z","end":"2026-09-17T18:46:42.150765Z","steps":["trace[1692489068] 'agreement among raft nodes before linearized reading'  (duration: 114.270012ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:45.517375Z","caller":"traceutil/trace.go:171","msg":"trace[1828369240] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"395.70328ms","start":"2026-09-17T18:46:45.121648Z","end":"2026-09-17T18:46:45.517351Z","steps":["trace[1828369240] 'process raft request'  (duration: 395.575264ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:46:45.517578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-17T18:46:45.121622Z","time spent":"395.893649ms","remote":"127.0.0.1:59106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2581,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-ck2vx\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls-ck2vx\" value_size:2489 >> failure:<>"}
{"level":"info","ts":"2026-09-17T18:46:45.518258Z","caller":"traceutil/trace.go:171","msg":"trace[120765524] transaction","detail":"{read_only:false; response_revision:5286; number_of_response:1; }","duration":"218.857624ms","start":"2026-09-17T18:46:45.299363Z","end":"2026-09-17T18:46:45.518221Z","steps":["trace[120765524] 'process raft request'  (duration: 218.606952ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:50.349845Z","caller":"traceutil/trace.go:171","msg":"trace[1154991739] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"210.820974ms","start":"2026-09-17T18:46:50.138995Z","end":"2026-09-17T18:46:50.349816Z","steps":["trace[1154991739] 'process raft request'  (duration: 210.551391ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:50.350144Z","caller":"traceutil/trace.go:171","msg":"trace[869217069] transaction","detail":"{read_only:false; response_revision:5381; number_of_response:1; }","duration":"210.943389ms","start":"2026-09-17T18:46:50.139167Z","end":"2026-09-17T18:46:50.350111Z","steps":["trace[869217069] 'process raft request'  (duration: 210.605472ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:50.350234Z","caller":"traceutil/trace.go:171","msg":"trace[1427755011] linearizableReadLoop","detail":"{readStateIndex:5591; appliedIndex:5588; }","duration":"198.028949ms","start":"2026-09-17T18:46:50.152173Z","end":"2026-09-17T18:46:50.350202Z","steps":["trace[1427755011] 'read index received'  (duration: 37.760752ms)","trace[1427755011] 'applied index is now lower than readState.Index'  (duration: 160.266927ms)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:46:50.350339Z","caller":"traceutil/trace.go:171","msg":"trace[1221893132] transaction","detail":"{read_only:false; response_revision:5382; number_of_response:1; }","duration":"198.675501ms","start":"2026-09-17T18:46:50.151651Z","end":"2026-09-17T18:46:50.350327Z","steps":["trace[1221893132] 'process raft request'  (duration: 198.412488ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:46:50.350527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.328734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"}
{"level":"info","ts":"2026-09-17T18:46:50.350571Z","caller":"traceutil/trace.go:171","msg":"trace[1168692293] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5382; }","duration":"198.414859ms","start":"2026-09-17T18:46:50.15214Z","end":"2026-09-17T18:46:50.350554Z","steps":["trace[1168692293] 'agreement among raft nodes before linearized reading'  (duration: 198.159976ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:46:50.600333Z","caller":"traceutil/trace.go:171","msg":"trace[1473670511] linearizableReadLoop","detail":"{readStateIndex:5593; appliedIndex:5592; }","duration":"111.695618ms","start":"2026-09-17T18:46:50.488613Z","end":"2026-09-17T18:46:50.600309Z","steps":["trace[1473670511] 'read index received'  (duration: 111.596344ms)","trace[1473670511] 'applied index is now lower than readState.Index'  (duration: 98.224µs)"],"step_count":2}
{"level":"info","ts":"2026-09-17T18:46:50.600505Z","caller":"traceutil/trace.go:171","msg":"trace[1741713044] transaction","detail":"{read_only:false; response_revision:5384; number_of_response:1; }","duration":"122.58324ms","start":"2026-09-17T18:46:50.477876Z","end":"2026-09-17T18:46:50.600459Z","steps":["trace[1741713044] 'process raft request'  (duration: 122.277485ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-17T18:46:50.600541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.914439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-17T18:46:50.600636Z","caller":"traceutil/trace.go:171","msg":"trace[1854221520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5384; }","duration":"112.006723ms","start":"2026-09-17T18:46:50.488565Z","end":"2026-09-17T18:46:50.600571Z","steps":["trace[1854221520] 'agreement among raft nodes before linearized reading'  (duration: 111.861726ms)"],"step_count":1}
{"level":"info","ts":"2026-09-17T18:51:35.862915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5124}
{"level":"info","ts":"2026-09-17T18:51:36.010703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5124,"took":"146.400264ms","hash":266890261,"current-db-size-bytes":51806208,"current-db-size":"52 MB","current-db-size-in-use-bytes":20025344,"current-db-size-in-use":"20 MB"}
{"level":"info","ts":"2026-09-17T18:51:36.010767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":266890261,"revision":5124,"compact-revision":2855}