{"level":"warn","ts":"2026-09-01T04:18:59.313113Z","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-01T04:18:59.313607Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.33:2380","--initial-cluster=controller=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33:2380","--name=controller","--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-01T04:18:59.313726Z","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-01T04:18:59.31374Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]}
{"level":"info","ts":"2026-09-01T04:18:59.313763Z","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-01T04:18:59.314776Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]}
{"level":"info","ts":"2026-09-01T04:18:59.314949Z","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":"controller","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"controller=https://199.204.45.33: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-01T04:18:59.321538Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.198033ms"}
{"level":"info","ts":"2026-09-01T04:18:59.335398Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"}
{"level":"info","ts":"2026-09-01T04:18:59.335511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"}
{"level":"info","ts":"2026-09-01T04:18:59.335537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"}
{"level":"info","ts":"2026-09-01T04:18:59.33555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-01T04:18:59.335578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"}
{"level":"info","ts":"2026-09-01T04:18:59.33562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"}
{"level":"warn","ts":"2026-09-01T04:18:59.346277Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-01T04:18:59.348061Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-01T04:18:59.349532Z","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-01T04:18:59.352135Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-01T04:18:59.352913Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-01T04:18:59.353241Z","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-01T04:18:59.353295Z","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-01T04:18:59.353305Z","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-01T04:18:59.357206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"}
{"level":"info","ts":"2026-09-01T04:18:59.357816Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]}
{"level":"info","ts":"2026-09-01T04:18:59.358822Z","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-01T04:18:59.35901Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"}
{"level":"info","ts":"2026-09-01T04:18:59.359049Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"}
{"level":"info","ts":"2026-09-01T04:18:59.359844Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-01T04:18:59.359874Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-01T04:19:00.336036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"}
{"level":"info","ts":"2026-09-01T04:19:00.336075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-01T04:19:00.336102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"}
{"level":"info","ts":"2026-09-01T04:19:00.336113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"}
{"level":"info","ts":"2026-09-01T04:19:00.336118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"}
{"level":"info","ts":"2026-09-01T04:19:00.336126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"}
{"level":"info","ts":"2026-09-01T04:19:00.336134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"}
{"level":"info","ts":"2026-09-01T04:19:00.337174Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T04:19:00.337928Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:controller ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-01T04:19:00.337957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-01T04:19:00.338024Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-01T04:19:00.338297Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-01T04:19:00.33833Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-01T04:19:00.338551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T04:19:00.338645Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T04:19:00.338668Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-01T04:19:00.340867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-01T04:19:00.341071Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"}
{"level":"info","ts":"2026-09-01T04:19:22.304885Z","caller":"traceutil/trace.go:171","msg":"trace[1056979606] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"231.669097ms","start":"2026-09-01T04:19:22.073194Z","end":"2026-09-01T04:19:22.304863Z","steps":["trace[1056979606] 'read index received'  (duration: 231.490381ms)","trace[1056979606] 'applied index is now lower than readState.Index'  (duration: 177.776µs)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:19:22.304987Z","caller":"traceutil/trace.go:171","msg":"trace[1967438650] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"233.396018ms","start":"2026-09-01T04:19:22.071552Z","end":"2026-09-01T04:19:22.304948Z","steps":["trace[1967438650] 'process raft request'  (duration: 233.198821ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:19:22.305688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.827152ms","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-01T04:19:22.305834Z","caller":"traceutil/trace.go:171","msg":"trace[1136715261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"232.658992ms","start":"2026-09-01T04:19:22.073154Z","end":"2026-09-01T04:19:22.305813Z","steps":["trace[1136715261] 'agreement among raft nodes before linearized reading'  (duration: 231.838413ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:19:22.54333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.42667ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088158886583110317 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:410 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:414 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T04:19:22.543454Z","caller":"traceutil/trace.go:171","msg":"trace[2136321261] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"143.348835ms","start":"2026-09-01T04:19:22.400087Z","end":"2026-09-01T04:19:22.543436Z","steps":["trace[2136321261] 'read index received'  (duration: 35.008282ms)","trace[2136321261] 'applied index is now lower than readState.Index'  (duration: 108.339223ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:19:22.543523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.447077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-09-01T04:19:22.543519Z","caller":"traceutil/trace.go:171","msg":"trace[706512918] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"233.274864ms","start":"2026-09-01T04:19:22.310216Z","end":"2026-09-01T04:19:22.54349Z","steps":["trace[706512918] 'process raft request'  (duration: 124.996003ms)","trace[706512918] 'compare'  (duration: 107.267885ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:19:22.543555Z","caller":"traceutil/trace.go:171","msg":"trace[1226030832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"143.51572ms","start":"2026-09-01T04:19:22.400029Z","end":"2026-09-01T04:19:22.543545Z","steps":["trace[1226030832] 'agreement among raft nodes before linearized reading'  (duration: 143.453448ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:19:32.462613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.823595ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088158886583110402 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/roles/kube-system/cilium-config-agent\" mod_revision:0 > success:<request_put:<key:\"/registry/roles/kube-system/cilium-config-agent\" value_size:625 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-09-01T04:19:32.462806Z","caller":"traceutil/trace.go:171","msg":"trace[884861226] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"232.805703ms","start":"2026-09-01T04:19:32.229949Z","end":"2026-09-01T04:19:32.462755Z","steps":["trace[884861226] 'process raft request'  (duration: 127.676007ms)","trace[884861226] 'compare'  (duration: 104.589597ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:19:40.71734Z","caller":"traceutil/trace.go:171","msg":"trace[343658850] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"215.628112ms","start":"2026-09-01T04:19:40.501266Z","end":"2026-09-01T04:19:40.716894Z","steps":["trace[343658850] 'process raft request'  (duration: 175.108173ms)","trace[343658850] 'compare'  (duration: 34.364656ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:19:40.948669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.33048ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088158886583110709 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" mod_revision:0 > success:<request_put:<key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" value_size:1527 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-09-01T04:19:40.948923Z","caller":"traceutil/trace.go:171","msg":"trace[1136479747] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"150.613354ms","start":"2026-09-01T04:19:40.798267Z","end":"2026-09-01T04:19:40.94888Z","steps":["trace[1136479747] 'process raft request'  (duration: 50.002434ms)","trace[1136479747] 'compare'  (duration: 100.150743ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:19:41.427486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.412709ms","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-01T04:19:41.427537Z","caller":"traceutil/trace.go:171","msg":"trace[1009980803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"143.516492ms","start":"2026-09-01T04:19:41.284009Z","end":"2026-09-01T04:19:41.427526Z","steps":["trace[1009980803] 'range keys from in-memory index tree'  (duration: 143.214482ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:19:58.761011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.559828ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088158886583111036 > lease_revoke:<id:7e1fa05b31188c70>","response":"size:28"}
{"level":"info","ts":"2026-09-01T04:19:58.761152Z","caller":"traceutil/trace.go:171","msg":"trace[44386764] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"232.77965ms","start":"2026-09-01T04:19:58.528353Z","end":"2026-09-01T04:19:58.761133Z","steps":["trace[44386764] 'read index received'  (duration: 55.992893ms)","trace[44386764] 'applied index is now lower than readState.Index'  (duration: 176.785007ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:19:58.761442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.057581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ldqsc\" ","response":"range_response_count:1 size:3966"}
{"level":"info","ts":"2026-09-01T04:19:58.761499Z","caller":"traceutil/trace.go:171","msg":"trace[1872257531] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ldqsc; range_end:; response_count:1; response_revision:754; }","duration":"233.168815ms","start":"2026-09-01T04:19:58.528316Z","end":"2026-09-01T04:19:58.761485Z","steps":["trace[1872257531] 'agreement among raft nodes before linearized reading'  (duration: 232.866064ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:21:09.138213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.766208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T04:21:09.138321Z","caller":"traceutil/trace.go:171","msg":"trace[2144359533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1479; }","duration":"154.966406ms","start":"2026-09-01T04:21:08.983332Z","end":"2026-09-01T04:21:09.138299Z","steps":["trace[2144359533] 'range keys from in-memory index tree'  (duration: 154.476647ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:21:35.010963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.123391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"}
{"level":"info","ts":"2026-09-01T04:21:35.011073Z","caller":"traceutil/trace.go:171","msg":"trace[1440603630] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1899; }","duration":"173.275318ms","start":"2026-09-01T04:21:34.837771Z","end":"2026-09-01T04:21:35.011046Z","steps":["trace[1440603630] 'range keys from in-memory index tree'  (duration: 172.904192ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:35.171629Z","caller":"traceutil/trace.go:171","msg":"trace[55780170] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"110.384798ms","start":"2026-09-01T04:21:35.061228Z","end":"2026-09-01T04:21:35.171613Z","steps":["trace[55780170] 'read index received'  (duration: 102.324323ms)","trace[55780170] 'applied index is now lower than readState.Index'  (duration: 8.059475ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:21:35.171653Z","caller":"traceutil/trace.go:171","msg":"trace[1584197035] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"149.863789ms","start":"2026-09-01T04:21:35.021771Z","end":"2026-09-01T04:21:35.171635Z","steps":["trace[1584197035] 'process raft request'  (duration: 141.761392ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:21:35.171746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.474271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T04:21:35.17177Z","caller":"traceutil/trace.go:171","msg":"trace[35092646] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1901; }","duration":"110.579756ms","start":"2026-09-01T04:21:35.061184Z","end":"2026-09-01T04:21:35.171763Z","steps":["trace[35092646] 'agreement among raft nodes before linearized reading'  (duration: 110.491703ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:21:35.575411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.678495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"warn","ts":"2026-09-01T04:21:35.575435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.91652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"}
{"level":"info","ts":"2026-09-01T04:21:35.575508Z","caller":"traceutil/trace.go:171","msg":"trace[1465889188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1902; }","duration":"195.80581ms","start":"2026-09-01T04:21:35.379679Z","end":"2026-09-01T04:21:35.575484Z","steps":["trace[1465889188] 'range keys from in-memory index tree'  (duration: 195.484367ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:35.575508Z","caller":"traceutil/trace.go:171","msg":"trace[614070120] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1902; }","duration":"115.009684ms","start":"2026-09-01T04:21:35.460484Z","end":"2026-09-01T04:21:35.575493Z","steps":["trace[614070120] 'range keys from in-memory index tree'  (duration: 114.817026ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:21:35.57661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.100773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-09-01T04:21:35.576802Z","caller":"traceutil/trace.go:171","msg":"trace[1255165076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1902; }","duration":"197.28865ms","start":"2026-09-01T04:21:35.37949Z","end":"2026-09-01T04:21:35.576779Z","steps":["trace[1255165076] 'range keys from in-memory index tree'  (duration: 196.867022ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:43.230954Z","caller":"traceutil/trace.go:171","msg":"trace[131401872] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"193.717908ms","start":"2026-09-01T04:21:43.037216Z","end":"2026-09-01T04:21:43.230934Z","steps":["trace[131401872] 'process raft request'  (duration: 193.591443ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:21:43.369757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.831628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T04:21:43.369881Z","caller":"traceutil/trace.go:171","msg":"trace[779066304] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1976; }","duration":"108.007626ms","start":"2026-09-01T04:21:43.261856Z","end":"2026-09-01T04:21:43.369864Z","steps":["trace[779066304] 'count revisions from in-memory index tree'  (duration: 107.730925ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:44.782873Z","caller":"traceutil/trace.go:171","msg":"trace[1077933944] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2064; }","duration":"152.700341ms","start":"2026-09-01T04:21:44.630152Z","end":"2026-09-01T04:21:44.782852Z","steps":["trace[1077933944] 'read index received'  (duration: 152.562355ms)","trace[1077933944] 'applied index is now lower than readState.Index'  (duration: 136.816µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:21:44.783074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.909378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"}
{"level":"info","ts":"2026-09-01T04:21:44.783106Z","caller":"traceutil/trace.go:171","msg":"trace[900066653] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1985; }","duration":"153.004722ms","start":"2026-09-01T04:21:44.630091Z","end":"2026-09-01T04:21:44.783096Z","steps":["trace[900066653] 'agreement among raft nodes before linearized reading'  (duration: 152.842806ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:44.78566Z","caller":"traceutil/trace.go:171","msg":"trace[1780082238] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"160.921444ms","start":"2026-09-01T04:21:44.622549Z","end":"2026-09-01T04:21:44.78347Z","steps":["trace[1780082238] 'process raft request'  (duration: 160.136232ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:49.749259Z","caller":"traceutil/trace.go:171","msg":"trace[931809703] linearizableReadLoop","detail":"{readStateIndex:2098; appliedIndex:2097; }","duration":"168.227873ms","start":"2026-09-01T04:21:49.581016Z","end":"2026-09-01T04:21:49.749243Z","steps":["trace[931809703] 'read index received'  (duration: 168.020104ms)","trace[931809703] 'applied index is now lower than readState.Index'  (duration: 207.318µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:21:49.74935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.338177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T04:21:49.749366Z","caller":"traceutil/trace.go:171","msg":"trace[1439084390] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2017; }","duration":"168.398039ms","start":"2026-09-01T04:21:49.580963Z","end":"2026-09-01T04:21:49.749361Z","steps":["trace[1439084390] 'agreement among raft nodes before linearized reading'  (duration: 168.353837ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:49.749363Z","caller":"traceutil/trace.go:171","msg":"trace[1157638282] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"177.583653ms","start":"2026-09-01T04:21:49.571741Z","end":"2026-09-01T04:21:49.749324Z","steps":["trace[1157638282] 'process raft request'  (duration: 177.404195ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:21:50.605052Z","caller":"traceutil/trace.go:171","msg":"trace[1608779517] linearizableReadLoop","detail":"{readStateIndex:2111; appliedIndex:2110; }","duration":"201.267671ms","start":"2026-09-01T04:21:50.403762Z","end":"2026-09-01T04:21:50.60503Z","steps":["trace[1608779517] 'read index received'  (duration: 201.011981ms)","trace[1608779517] 'applied index is now lower than readState.Index'  (duration: 254.48µs)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:21:50.605141Z","caller":"traceutil/trace.go:171","msg":"trace[397902639] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"212.989258ms","start":"2026-09-01T04:21:50.392108Z","end":"2026-09-01T04:21:50.605098Z","steps":["trace[397902639] 'process raft request'  (duration: 212.776599ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:21:50.605173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.385316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T04:21:50.605214Z","caller":"traceutil/trace.go:171","msg":"trace[1066235331] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2030; }","duration":"201.474139ms","start":"2026-09-01T04:21:50.403731Z","end":"2026-09-01T04:21:50.605205Z","steps":["trace[1066235331] 'agreement among raft nodes before linearized reading'  (duration: 201.389076ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:05.565436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.995151ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088158886583113790 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:2107 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T04:22:05.565527Z","caller":"traceutil/trace.go:171","msg":"trace[940539097] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"250.73403ms","start":"2026-09-01T04:22:05.314779Z","end":"2026-09-01T04:22:05.565513Z","steps":["trace[940539097] 'process raft request'  (duration: 63.586962ms)","trace[940539097] 'compare'  (duration: 186.487511ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:22:12.13101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.773132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T04:22:12.13111Z","caller":"traceutil/trace.go:171","msg":"trace[288602495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2152; }","duration":"145.926399ms","start":"2026-09-01T04:22:11.985144Z","end":"2026-09-01T04:22:12.13107Z","steps":["trace[288602495] 'range keys from in-memory index tree'  (duration: 145.660268ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:12.131252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.867775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-09-01T04:22:12.131358Z","caller":"traceutil/trace.go:171","msg":"trace[1354101371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2152; }","duration":"169.182857ms","start":"2026-09-01T04:22:11.962145Z","end":"2026-09-01T04:22:12.131328Z","steps":["trace[1354101371] 'range keys from in-memory index tree'  (duration: 168.663306ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:12.131322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.358669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-09-01T04:22:12.131422Z","caller":"traceutil/trace.go:171","msg":"trace[1849028183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2152; }","duration":"172.517555ms","start":"2026-09-01T04:22:11.958881Z","end":"2026-09-01T04:22:12.131399Z","steps":["trace[1849028183] 'range keys from in-memory index tree'  (duration: 172.092517ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:22:12.131541Z","caller":"traceutil/trace.go:171","msg":"trace[226787468] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"109.845721ms","start":"2026-09-01T04:22:12.021681Z","end":"2026-09-01T04:22:12.131527Z","steps":["trace[226787468] 'read index received'  (duration: 52.240075ms)","trace[226787468] 'applied index is now lower than readState.Index'  (duration: 57.604466ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:22:12.13165Z","caller":"traceutil/trace.go:171","msg":"trace[162255055] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"129.869107ms","start":"2026-09-01T04:22:12.001755Z","end":"2026-09-01T04:22:12.131624Z","steps":["trace[162255055] 'process raft request'  (duration: 72.131535ms)","trace[162255055] 'compare'  (duration: 57.147787ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:22:12.131695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.027178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"}
{"level":"info","ts":"2026-09-01T04:22:12.131728Z","caller":"traceutil/trace.go:171","msg":"trace[171945171] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"110.096111ms","start":"2026-09-01T04:22:12.021619Z","end":"2026-09-01T04:22:12.131715Z","steps":["trace[171945171] 'agreement among raft nodes before linearized reading'  (duration: 110.014178ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:22:12.345444Z","caller":"traceutil/trace.go:171","msg":"trace[1231627147] linearizableReadLoop","detail":"{readStateIndex:2242; appliedIndex:2239; }","duration":"178.094676ms","start":"2026-09-01T04:22:12.167302Z","end":"2026-09-01T04:22:12.345397Z","steps":["trace[1231627147] 'read index received'  (duration: 51.902951ms)","trace[1231627147] 'applied index is now lower than readState.Index'  (duration: 126.190585ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:22:12.345558Z","caller":"traceutil/trace.go:171","msg":"trace[1112430768] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"206.960027ms","start":"2026-09-01T04:22:12.138542Z","end":"2026-09-01T04:22:12.345502Z","steps":["trace[1112430768] 'process raft request'  (duration: 206.81215ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:12.345667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.332516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T04:22:12.345731Z","caller":"traceutil/trace.go:171","msg":"trace[110684675] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2156; }","duration":"178.469901ms","start":"2026-09-01T04:22:12.167247Z","end":"2026-09-01T04:22:12.345717Z","steps":["trace[110684675] 'agreement among raft nodes before linearized reading'  (duration: 178.312205ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:22:12.34547Z","caller":"traceutil/trace.go:171","msg":"trace[1673014225] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"208.01458ms","start":"2026-09-01T04:22:12.137424Z","end":"2026-09-01T04:22:12.345438Z","steps":["trace[1673014225] 'process raft request'  (duration: 207.652555ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:22:12.910235Z","caller":"traceutil/trace.go:171","msg":"trace[1599653009] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"178.653408ms","start":"2026-09-01T04:22:12.731551Z","end":"2026-09-01T04:22:12.910205Z","steps":["trace[1599653009] 'process raft request'  (duration: 178.44888ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:13.158883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.078311ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088158886583113882 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:2117 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T04:22:13.159011Z","caller":"traceutil/trace.go:171","msg":"trace[1513611544] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2243; }","duration":"349.799022ms","start":"2026-09-01T04:22:12.809194Z","end":"2026-09-01T04:22:13.158994Z","steps":["trace[1513611544] 'read index received'  (duration: 100.839009ms)","trace[1513611544] 'applied index is now lower than readState.Index'  (duration: 248.958603ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:22:13.159163Z","caller":"traceutil/trace.go:171","msg":"trace[62018226] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"405.02385ms","start":"2026-09-01T04:22:12.754067Z","end":"2026-09-01T04:22:13.15909Z","steps":["trace[62018226] 'process raft request'  (duration: 256.631615ms)","trace[62018226] 'compare'  (duration: 147.903845ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:22:13.159226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.044012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d117a4798e7334\" ","response":"range_response_count:1 size:850"}
{"level":"info","ts":"2026-09-01T04:22:13.159276Z","caller":"traceutil/trace.go:171","msg":"trace[1114877261] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d117a4798e7334; range_end:; response_count:1; response_revision:2159; }","duration":"350.122466ms","start":"2026-09-01T04:22:12.809135Z","end":"2026-09-01T04:22:13.159257Z","steps":["trace[1114877261] 'agreement among raft nodes before linearized reading'  (duration: 349.929068ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:13.159327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:12.809073Z","time spent":"350.24195ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":873,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d117a4798e7334\" "}
{"level":"warn","ts":"2026-09-01T04:22:13.159383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:12.75405Z","time spent":"405.205408ms","remote":"127.0.0.1:34292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:2117 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"info","ts":"2026-09-01T04:22:13.358126Z","caller":"traceutil/trace.go:171","msg":"trace[1516001518] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"196.427867ms","start":"2026-09-01T04:22:13.161647Z","end":"2026-09-01T04:22:13.358074Z","steps":["trace[1516001518] 'process raft request'  (duration: 196.348714ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:22:13.358337Z","caller":"traceutil/trace.go:171","msg":"trace[327267420] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"366.318648ms","start":"2026-09-01T04:22:12.991991Z","end":"2026-09-01T04:22:13.358309Z","steps":["trace[327267420] 'process raft request'  (duration: 318.515815ms)","trace[327267420] 'compare'  (duration: 47.343034ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:22:13.35836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.499187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"}
{"level":"info","ts":"2026-09-01T04:22:13.358423Z","caller":"traceutil/trace.go:171","msg":"trace[1363877419] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2161; }","duration":"217.591941ms","start":"2026-09-01T04:22:13.140809Z","end":"2026-09-01T04:22:13.358401Z","steps":["trace[1363877419] 'agreement among raft nodes before linearized reading'  (duration: 217.394513ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:13.358452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:12.991969Z","time spent":"366.425572ms","remote":"127.0.0.1:34292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:2150 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-09-01T04:22:13.358209Z","caller":"traceutil/trace.go:171","msg":"trace[1147213485] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2245; }","duration":"199.055966ms","start":"2026-09-01T04:22:13.159031Z","end":"2026-09-01T04:22:13.358087Z","steps":["trace[1147213485] 'read index received'  (duration: 151.492283ms)","trace[1147213485] 'applied index is now lower than readState.Index'  (duration: 47.560603ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:22:13.358603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.202532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T04:22:13.358957Z","caller":"traceutil/trace.go:171","msg":"trace[953939163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2161; }","duration":"373.646981ms","start":"2026-09-01T04:22:12.985294Z","end":"2026-09-01T04:22:13.358941Z","steps":["trace[953939163] 'agreement among raft nodes before linearized reading'  (duration: 373.226304ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:13.358999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:12.985263Z","time spent":"373.723884ms","remote":"127.0.0.1:34064","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-01T04:22:13.35881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.800454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-01T04:22:13.359129Z","caller":"traceutil/trace.go:171","msg":"trace[1762290533] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2161; }","duration":"508.111147ms","start":"2026-09-01T04:22:12.850969Z","end":"2026-09-01T04:22:13.35908Z","steps":["trace[1762290533] 'agreement among raft nodes before linearized reading'  (duration: 507.762962ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-01T04:22:13.359196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:12.850953Z","time spent":"508.220712ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "}
{"level":"warn","ts":"2026-09-01T04:27:43.716918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.510531ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088158886583119081 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:4088 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:416 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-01T04:27:43.717355Z","caller":"traceutil/trace.go:171","msg":"trace[1347657592] transaction","detail":"{read_only:false; response_revision:4223; number_of_response:1; }","duration":"124.97207ms","start":"2026-09-01T04:27:43.592366Z","end":"2026-09-01T04:27:43.717338Z","steps":["trace[1347657592] 'process raft request'  (duration: 18.965576ms)","trace[1347657592] 'compare'  (duration: 105.022339ms)"],"step_count":2}
{"level":"info","ts":"2026-09-01T04:29:00.380002Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2824}
{"level":"info","ts":"2026-09-01T04:29:00.496157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2824,"took":"114.406817ms","hash":647842466,"current-db-size-bytes":37195776,"current-db-size":"37 MB","current-db-size-in-use-bytes":12226560,"current-db-size-in-use":"12 MB"}
{"level":"info","ts":"2026-09-01T04:29:00.496226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":647842466,"revision":2824,"compact-revision":-1}
{"level":"info","ts":"2026-09-01T04:31:39.089949Z","caller":"traceutil/trace.go:171","msg":"trace[1199295750] linearizableReadLoop","detail":"{readStateIndex:6095; appliedIndex:6094; }","duration":"104.628141ms","start":"2026-09-01T04:31:38.985304Z","end":"2026-09-01T04:31:39.089932Z","steps":["trace[1199295750] 'read index received'  (duration: 104.465269ms)","trace[1199295750] 'applied index is now lower than readState.Index'  (duration: 162.332µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-01T04:31:39.090074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.763102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-01T04:31:39.090096Z","caller":"traceutil/trace.go:171","msg":"trace[1711656216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5876; }","duration":"104.827484ms","start":"2026-09-01T04:31:38.985262Z","end":"2026-09-01T04:31:39.090089Z","steps":["trace[1711656216] 'agreement among raft nodes before linearized reading'  (duration: 104.740372ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:31:39.090332Z","caller":"traceutil/trace.go:171","msg":"trace[116031750] transaction","detail":"{read_only:false; response_revision:5876; number_of_response:1; }","duration":"121.493662ms","start":"2026-09-01T04:31:38.968827Z","end":"2026-09-01T04:31:39.09032Z","steps":["trace[116031750] 'process raft request'  (duration: 120.995616ms)"],"step_count":1}
{"level":"info","ts":"2026-09-01T04:34:00.387982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4753}
{"level":"info","ts":"2026-09-01T04:34:00.501972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4753,"took":"112.850267ms","hash":1724357385,"current-db-size-bytes":37195776,"current-db-size":"37 MB","current-db-size-in-use-bytes":14368768,"current-db-size-in-use":"14 MB"}
{"level":"info","ts":"2026-09-01T04:34:00.50204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1724357385,"revision":4753,"compact-revision":2824}