{"level":"warn","ts":"2026-09-07T18:21:36.485483Z","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-07T18:21:36.485615Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.38: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.38:2380","--initial-cluster=instance=https://162.253.55.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.38: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-07T18:21:36.485719Z","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-07T18:21:36.48576Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.38:2380"]}
{"level":"info","ts":"2026-09-07T18:21:36.485809Z","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-07T18:21:36.486676Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"]}
{"level":"info","ts":"2026-09-07T18:21:36.486842Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.38: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-07T18:21:36.491854Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.711909ms"}
{"level":"info","ts":"2026-09-07T18:21:36.498536Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30b39d71f9e69e7c","cluster-id":"2afc6404f924db16"}
{"level":"info","ts":"2026-09-07T18:21:36.498666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=()"}
{"level":"info","ts":"2026-09-07T18:21:36.498692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 0"}
{"level":"info","ts":"2026-09-07T18:21:36.498746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30b39d71f9e69e7c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-07T18:21:36.498766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became follower at term 1"}
{"level":"info","ts":"2026-09-07T18:21:36.49883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"}
{"level":"warn","ts":"2026-09-07T18:21:36.505953Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-07T18:21:36.507826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-07T18:21:36.509395Z","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-07T18:21:36.512531Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30b39d71f9e69e7c","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-07T18:21:36.513274Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30b39d71f9e69e7c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-07T18:21:36.513268Z","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-07T18:21:36.513967Z","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-07T18:21:36.513999Z","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-07T18:21:36.515994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c switched to configuration voters=(3509321647501254268)"}
{"level":"info","ts":"2026-09-07T18:21:36.516273Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","added-peer-id":"30b39d71f9e69e7c","added-peer-peer-urls":["https://162.253.55.38:2380"]}
{"level":"info","ts":"2026-09-07T18:21:36.518645Z","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-07T18:21:36.518788Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.38:2380"}
{"level":"info","ts":"2026-09-07T18:21:36.518827Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.38:2380"}
{"level":"info","ts":"2026-09-07T18:21:36.519066Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30b39d71f9e69e7c","initial-advertise-peer-urls":["https://162.253.55.38:2380"],"listen-peer-urls":["https://162.253.55.38:2380"],"advertise-client-urls":["https://162.253.55.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-07T18:21:36.519142Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-07T18:21:37.099975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c is starting a new election at term 1"}
{"level":"info","ts":"2026-09-07T18:21:37.100092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-07T18:21:37.100137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgPreVoteResp from 30b39d71f9e69e7c at term 1"}
{"level":"info","ts":"2026-09-07T18:21:37.100165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became candidate at term 2"}
{"level":"info","ts":"2026-09-07T18:21:37.10018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c received MsgVoteResp from 30b39d71f9e69e7c at term 2"}
{"level":"info","ts":"2026-09-07T18:21:37.100204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30b39d71f9e69e7c became leader at term 2"}
{"level":"info","ts":"2026-09-07T18:21:37.100223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30b39d71f9e69e7c elected leader 30b39d71f9e69e7c at term 2"}
{"level":"info","ts":"2026-09-07T18:21:37.101397Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T18:21:37.102337Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30b39d71f9e69e7c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.38:2379]}","request-path":"/0/members/30b39d71f9e69e7c/attributes","cluster-id":"2afc6404f924db16","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-07T18:21:37.102379Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-07T18:21:37.102439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-07T18:21:37.102573Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-07T18:21:37.102597Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-07T18:21:37.103158Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2afc6404f924db16","local-member-id":"30b39d71f9e69e7c","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T18:21:37.10334Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T18:21:37.103395Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-07T18:21:37.105751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.38:2379"}
{"level":"info","ts":"2026-09-07T18:21:37.106538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-09-07T18:21:54.78731Z","caller":"traceutil/trace.go:171","msg":"trace[563627529] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"113.274457ms","start":"2026-09-07T18:21:54.674003Z","end":"2026-09-07T18:21:54.787277Z","steps":["trace[563627529] 'process raft request'  (duration: 113.078342ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:22:23.080102Z","caller":"traceutil/trace.go:171","msg":"trace[1738347863] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"131.960421ms","start":"2026-09-07T18:22:22.948107Z","end":"2026-09-07T18:22:23.080067Z","steps":["trace[1738347863] 'read index received'  (duration: 130.947091ms)","trace[1738347863] 'applied index is now lower than readState.Index'  (duration: 1.01232ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:22:23.080198Z","caller":"traceutil/trace.go:171","msg":"trace[1200378070] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"166.23631ms","start":"2026-09-07T18:22:22.913907Z","end":"2026-09-07T18:22:23.080143Z","steps":["trace[1200378070] 'process raft request'  (duration: 165.478287ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:22:23.080403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.27143ms","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-07T18:22:23.080535Z","caller":"traceutil/trace.go:171","msg":"trace[893666998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"132.465416ms","start":"2026-09-07T18:22:22.948045Z","end":"2026-09-07T18:22:23.080511Z","steps":["trace[893666998] 'agreement among raft nodes before linearized reading'  (duration: 132.141737ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:22:23.313607Z","caller":"traceutil/trace.go:171","msg":"trace[254791644] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"153.699401ms","start":"2026-09-07T18:22:23.15988Z","end":"2026-09-07T18:22:23.31358Z","steps":["trace[254791644] 'process raft request'  (duration: 153.533737ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:22:23.532458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.577105ms","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-07T18:22:23.532558Z","caller":"traceutil/trace.go:171","msg":"trace[1187313914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"191.717529ms","start":"2026-09-07T18:22:23.340814Z","end":"2026-09-07T18:22:23.532532Z","steps":["trace[1187313914] 'range keys from in-memory index tree'  (duration: 191.38617ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:22:23.532552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.132181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:22:23.532673Z","caller":"traceutil/trace.go:171","msg":"trace[1200379889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"159.524193ms","start":"2026-09-07T18:22:23.373128Z","end":"2026-09-07T18:22:23.532652Z","steps":["trace[1200379889] 'range keys from in-memory index tree'  (duration: 158.990877ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:22:23.759976Z","caller":"traceutil/trace.go:171","msg":"trace[1603778130] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"224.238726ms","start":"2026-09-07T18:22:23.535668Z","end":"2026-09-07T18:22:23.759907Z","steps":["trace[1603778130] 'process raft request'  (duration: 223.848905ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:22:24.074096Z","caller":"traceutil/trace.go:171","msg":"trace[1831966216] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"535.727189ms","start":"2026-09-07T18:22:23.538344Z","end":"2026-09-07T18:22:24.074071Z","steps":["trace[1831966216] 'process raft request'  (duration: 535.492842ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:22:24.074301Z","caller":"traceutil/trace.go:171","msg":"trace[1180607008] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:652; }","duration":"381.329826ms","start":"2026-09-07T18:22:23.692924Z","end":"2026-09-07T18:22:24.074254Z","steps":["trace[1180607008] 'read index received'  (duration: 66.561837ms)","trace[1180607008] 'applied index is now lower than readState.Index'  (duration: 314.764909ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:22:24.074485Z","caller":"traceutil/trace.go:171","msg":"trace[1782605248] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"522.996343ms","start":"2026-09-07T18:22:23.551438Z","end":"2026-09-07T18:22:24.074434Z","steps":["trace[1782605248] 'process raft request'  (duration: 522.54012ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:22:24.074619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.645846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-07T18:22:24.074663Z","caller":"traceutil/trace.go:171","msg":"trace[1927807402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"381.793689ms","start":"2026-09-07T18:22:23.692855Z","end":"2026-09-07T18:22:24.074649Z","steps":["trace[1927807402] 'agreement among raft nodes before linearized reading'  (duration: 381.556562ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:22:24.074707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:23.692819Z","time spent":"381.877592ms","remote":"127.0.0.1:58978","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-07T18:22:24.074753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:23.53832Z","time spent":"535.853283ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:618 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-09-07T18:22:24.074771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:23.551415Z","time spent":"523.206419ms","remote":"127.0.0.1:58904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-r2rg2\" mod_revision:518 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-r2rg2\" value_size:17081 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-r2rg2\" > >"}
{"level":"info","ts":"2026-09-07T18:22:24.084168Z","caller":"traceutil/trace.go:171","msg":"trace[166044634] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"317.900881ms","start":"2026-09-07T18:22:23.766217Z","end":"2026-09-07T18:22:24.084118Z","steps":["trace[166044634] 'process raft request'  (duration: 317.302104ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:22:24.084352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:22:23.76613Z","time spent":"318.118298ms","remote":"127.0.0.1:58832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:630 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036197 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"warn","ts":"2026-09-07T18:22:40.729444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.396967ms","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-07T18:22:40.729596Z","caller":"traceutil/trace.go:171","msg":"trace[426044102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"161.569502ms","start":"2026-09-07T18:22:40.567968Z","end":"2026-09-07T18:22:40.729538Z","steps":["trace[426044102] 'range keys from in-memory index tree'  (duration: 161.183001ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:23:10.690601Z","caller":"traceutil/trace.go:171","msg":"trace[1092202811] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"167.064825ms","start":"2026-09-07T18:23:10.523489Z","end":"2026-09-07T18:23:10.690554Z","steps":["trace[1092202811] 'read index received'  (duration: 121.67937ms)","trace[1092202811] 'applied index is now lower than readState.Index'  (duration: 45.384055ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:23:10.690782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.268661ms","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-07T18:23:10.69082Z","caller":"traceutil/trace.go:171","msg":"trace[1909893615] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1199; }","duration":"167.376123ms","start":"2026-09-07T18:23:10.523432Z","end":"2026-09-07T18:23:10.690808Z","steps":["trace[1909893615] 'agreement among raft nodes before linearized reading'  (duration: 167.275751ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:23:10.690743Z","caller":"traceutil/trace.go:171","msg":"trace[1912883630] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"290.873336ms","start":"2026-09-07T18:23:10.399803Z","end":"2026-09-07T18:23:10.690676Z","steps":["trace[1912883630] 'process raft request'  (duration: 245.386137ms)","trace[1912883630] 'compare'  (duration: 45.241572ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:23:10.692952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.561748ms","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-07T18:23:10.693038Z","caller":"traceutil/trace.go:171","msg":"trace[959846997] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1200; }","duration":"168.704633ms","start":"2026-09-07T18:23:10.524315Z","end":"2026-09-07T18:23:10.693019Z","steps":["trace[959846997] 'agreement among raft nodes before linearized reading'  (duration: 168.488256ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:23:58.735785Z","caller":"traceutil/trace.go:171","msg":"trace[1801714127] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"104.747365ms","start":"2026-09-07T18:23:58.631011Z","end":"2026-09-07T18:23:58.735758Z","steps":["trace[1801714127] 'process raft request'  (duration: 104.58274ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:23:59.092494Z","caller":"traceutil/trace.go:171","msg":"trace[983477174] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"138.052457ms","start":"2026-09-07T18:23:58.954405Z","end":"2026-09-07T18:23:59.092458Z","steps":["trace[983477174] 'process raft request'  (duration: 137.838061ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:23:59.147116Z","caller":"traceutil/trace.go:171","msg":"trace[2099996072] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"112.810362ms","start":"2026-09-07T18:23:59.034282Z","end":"2026-09-07T18:23:59.147092Z","steps":["trace[2099996072] 'process raft request'  (duration: 110.682096ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:24:11.815382Z","caller":"traceutil/trace.go:171","msg":"trace[1463288257] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"103.772044ms","start":"2026-09-07T18:24:11.711571Z","end":"2026-09-07T18:24:11.815343Z","steps":["trace[1463288257] 'process raft request'  (duration: 103.484375ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:24:44.469199Z","caller":"traceutil/trace.go:171","msg":"trace[273099264] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"275.661902ms","start":"2026-09-07T18:24:44.193509Z","end":"2026-09-07T18:24:44.46917Z","steps":["trace[273099264] 'process raft request'  (duration: 275.497987ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:24:44.482597Z","caller":"traceutil/trace.go:171","msg":"trace[1630219160] linearizableReadLoop","detail":"{readStateIndex:2245; appliedIndex:2243; }","duration":"187.760991ms","start":"2026-09-07T18:24:44.294804Z","end":"2026-09-07T18:24:44.482565Z","steps":["trace[1630219160] 'read index received'  (duration: 174.169561ms)","trace[1630219160] 'applied index is now lower than readState.Index'  (duration: 13.58978ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:24:44.482663Z","caller":"traceutil/trace.go:171","msg":"trace[1902924448] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"194.68397ms","start":"2026-09-07T18:24:44.287949Z","end":"2026-09-07T18:24:44.482633Z","steps":["trace[1902924448] 'process raft request'  (duration: 194.442033ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:24:44.482972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.140062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-09-07T18:24:44.483022Z","caller":"traceutil/trace.go:171","msg":"trace[1444065441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2167; }","duration":"188.254485ms","start":"2026-09-07T18:24:44.294755Z","end":"2026-09-07T18:24:44.48301Z","steps":["trace[1444065441] 'agreement among raft nodes before linearized reading'  (duration: 187.984887ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:24:44.482876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.79478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:24:44.48317Z","caller":"traceutil/trace.go:171","msg":"trace[1328926009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2167; }","duration":"108.844041ms","start":"2026-09-07T18:24:44.374316Z","end":"2026-09-07T18:24:44.48316Z","steps":["trace[1328926009] 'agreement among raft nodes before linearized reading'  (duration: 108.48283ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:25:38.779437Z","caller":"traceutil/trace.go:171","msg":"trace[185807966] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"102.319917ms","start":"2026-09-07T18:25:38.677096Z","end":"2026-09-07T18:25:38.779416Z","steps":["trace[185807966] 'process raft request'  (duration: 102.214144ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:26:12.176172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.18377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:26:12.176289Z","caller":"traceutil/trace.go:171","msg":"trace[981168109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2838; }","duration":"118.375465ms","start":"2026-09-07T18:26:12.057891Z","end":"2026-09-07T18:26:12.176267Z","steps":["trace[981168109] 'count revisions from in-memory index tree'  (duration: 118.032275ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:28:58.36725Z","caller":"traceutil/trace.go:171","msg":"trace[1874773270] transaction","detail":"{read_only:false; response_revision:3857; number_of_response:1; }","duration":"101.219123ms","start":"2026-09-07T18:28:58.266003Z","end":"2026-09-07T18:28:58.367223Z","steps":["trace[1874773270] 'process raft request'  (duration: 86.330418ms)","trace[1874773270] 'compare'  (duration: 14.761611ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:28:58.368645Z","caller":"traceutil/trace.go:171","msg":"trace[852389949] transaction","detail":"{read_only:false; response_revision:3859; number_of_response:1; }","duration":"100.721789ms","start":"2026-09-07T18:28:58.267903Z","end":"2026-09-07T18:28:58.368624Z","steps":["trace[852389949] 'process raft request'  (duration: 100.603925ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:28:58.368692Z","caller":"traceutil/trace.go:171","msg":"trace[2060907679] transaction","detail":"{read_only:false; response_revision:3858; number_of_response:1; }","duration":"102.066218ms","start":"2026-09-07T18:28:58.266592Z","end":"2026-09-07T18:28:58.368658Z","steps":["trace[2060907679] 'process raft request'  (duration: 101.78942ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:28:58.368711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.092331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/secretgen-controller/\" range_end:\"/registry/limitranges/secretgen-controller0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:28:58.368829Z","caller":"traceutil/trace.go:171","msg":"trace[915025329] range","detail":"{range_begin:/registry/limitranges/secretgen-controller/; range_end:/registry/limitranges/secretgen-controller0; response_count:0; response_revision:3859; }","duration":"100.245535ms","start":"2026-09-07T18:28:58.268542Z","end":"2026-09-07T18:28:58.368788Z","steps":["trace[915025329] 'agreement among raft nodes before linearized reading'  (duration: 100.038149ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:29:30.926357Z","caller":"traceutil/trace.go:171","msg":"trace[295719304] linearizableReadLoop","detail":"{readStateIndex:4237; appliedIndex:4236; }","duration":"156.055143ms","start":"2026-09-07T18:29:30.770281Z","end":"2026-09-07T18:29:30.926337Z","steps":["trace[295719304] 'read index received'  (duration: 149.661486ms)","trace[295719304] 'applied index is now lower than readState.Index'  (duration: 6.392657ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:29:30.926413Z","caller":"traceutil/trace.go:171","msg":"trace[817470079] transaction","detail":"{read_only:false; response_revision:4084; number_of_response:1; }","duration":"169.830225ms","start":"2026-09-07T18:29:30.75654Z","end":"2026-09-07T18:29:30.92637Z","steps":["trace[817470079] 'process raft request'  (duration: 163.308114ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:29:30.926773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.884954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-09-07T18:29:30.926774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.277437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-09-07T18:29:30.92684Z","caller":"traceutil/trace.go:171","msg":"trace[1440501613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4084; }","duration":"100.986406ms","start":"2026-09-07T18:29:30.825834Z","end":"2026-09-07T18:29:30.92682Z","steps":["trace[1440501613] 'agreement among raft nodes before linearized reading'  (duration: 100.699778ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:29:30.92692Z","caller":"traceutil/trace.go:171","msg":"trace[1549112555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4084; }","duration":"110.487704ms","start":"2026-09-07T18:29:30.816404Z","end":"2026-09-07T18:29:30.926891Z","steps":["trace[1549112555] 'agreement among raft nodes before linearized reading'  (duration: 110.106973ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:29:30.928241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.930717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62859"}
{"level":"info","ts":"2026-09-07T18:29:30.928403Z","caller":"traceutil/trace.go:171","msg":"trace[1910213099] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4084; }","duration":"158.134563ms","start":"2026-09-07T18:29:30.770242Z","end":"2026-09-07T18:29:30.928376Z","steps":["trace[1910213099] 'agreement among raft nodes before linearized reading'  (duration: 156.184017ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:04.048056Z","caller":"traceutil/trace.go:171","msg":"trace[401279127] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"102.057154ms","start":"2026-09-07T18:31:03.945983Z","end":"2026-09-07T18:31:04.04804Z","steps":["trace[401279127] 'process raft request'  (duration: 101.962031ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:07.780927Z","caller":"traceutil/trace.go:171","msg":"trace[1446738639] linearizableReadLoop","detail":"{readStateIndex:5514; appliedIndex:5513; }","duration":"296.74161ms","start":"2026-09-07T18:31:07.484125Z","end":"2026-09-07T18:31:07.780867Z","steps":["trace[1446738639] 'read index received'  (duration: 231.859924ms)","trace[1446738639] 'applied index is now lower than readState.Index'  (duration: 64.880005ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:31:07.781043Z","caller":"traceutil/trace.go:171","msg":"trace[904091629] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"347.480574ms","start":"2026-09-07T18:31:07.43347Z","end":"2026-09-07T18:31:07.78095Z","steps":["trace[904091629] 'process raft request'  (duration: 282.607619ms)","trace[904091629] 'compare'  (duration: 64.470402ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:31:07.781191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.042708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ef5602a-15e5-4149-b69a-0bc5bbd25f52\" ","response":"range_response_count:1 size:3720"}
{"level":"info","ts":"2026-09-07T18:31:07.781304Z","caller":"traceutil/trace.go:171","msg":"trace[60129931] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4ef5602a-15e5-4149-b69a-0bc5bbd25f52; range_end:; response_count:1; response_revision:5310; }","duration":"297.195852ms","start":"2026-09-07T18:31:07.484084Z","end":"2026-09-07T18:31:07.78128Z","steps":["trace[60129931] 'agreement among raft nodes before linearized reading'  (duration: 296.915504ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:07.781304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:07.433438Z","time spent":"347.742801ms","remote":"127.0.0.1:58832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58202,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/secrets/monitoring/sh.helm.release.v1.vector.v1\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/monitoring/sh.helm.release.v1.vector.v1\" value_size:58135 >> failure:<>"}
{"level":"warn","ts":"2026-09-07T18:31:07.781365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.660208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"warn","ts":"2026-09-07T18:31:07.781418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.270249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jjzhd\" ","response":"range_response_count:1 size:6520"}
{"level":"info","ts":"2026-09-07T18:31:07.781447Z","caller":"traceutil/trace.go:171","msg":"trace[285091870] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5310; }","duration":"211.784231ms","start":"2026-09-07T18:31:07.56964Z","end":"2026-09-07T18:31:07.781425Z","steps":["trace[285091870] 'agreement among raft nodes before linearized reading'  (duration: 211.573175ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:07.781467Z","caller":"traceutil/trace.go:171","msg":"trace[1421852043] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jjzhd; range_end:; response_count:1; response_revision:5310; }","duration":"240.351812ms","start":"2026-09-07T18:31:07.541095Z","end":"2026-09-07T18:31:07.781447Z","steps":["trace[1421852043] 'agreement among raft nodes before linearized reading'  (duration: 240.242739ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:07.781304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.544198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-07T18:31:07.781617Z","caller":"traceutil/trace.go:171","msg":"trace[854915627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5310; }","duration":"270.910068ms","start":"2026-09-07T18:31:07.510683Z","end":"2026-09-07T18:31:07.781593Z","steps":["trace[854915627] 'agreement among raft nodes before linearized reading'  (duration: 270.416604ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:07.781629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.323906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-09-07T18:31:07.781687Z","caller":"traceutil/trace.go:171","msg":"trace[196935725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5310; }","duration":"245.409668ms","start":"2026-09-07T18:31:07.536264Z","end":"2026-09-07T18:31:07.781674Z","steps":["trace[196935725] 'agreement among raft nodes before linearized reading'  (duration: 245.249304ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:07.781665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.197046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:31:07.781752Z","caller":"traceutil/trace.go:171","msg":"trace[1635197877] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5310; }","duration":"251.326859ms","start":"2026-09-07T18:31:07.530406Z","end":"2026-09-07T18:31:07.781733Z","steps":["trace[1635197877] 'agreement among raft nodes before linearized reading'  (duration: 251.178135ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:08.139658Z","caller":"traceutil/trace.go:171","msg":"trace[1941568971] linearizableReadLoop","detail":"{readStateIndex:5527; appliedIndex:5526; }","duration":"183.285754ms","start":"2026-09-07T18:31:07.956342Z","end":"2026-09-07T18:31:08.139628Z","steps":["trace[1941568971] 'read index received'  (duration: 164.613621ms)","trace[1941568971] 'applied index is now lower than readState.Index'  (duration: 18.670743ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:31:08.139732Z","caller":"traceutil/trace.go:171","msg":"trace[892118868] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"184.237762ms","start":"2026-09-07T18:31:07.955466Z","end":"2026-09-07T18:31:08.139704Z","steps":["trace[892118868] 'process raft request'  (duration: 165.475656ms)","trace[892118868] 'compare'  (duration: 18.53609ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:31:08.139945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.562031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16980"}
{"level":"info","ts":"2026-09-07T18:31:08.140059Z","caller":"traceutil/trace.go:171","msg":"trace[406742896] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5323; }","duration":"183.658365ms","start":"2026-09-07T18:31:07.956318Z","end":"2026-09-07T18:31:08.139977Z","steps":["trace[406742896] 'agreement among raft nodes before linearized reading'  (duration: 183.439458ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:08.162855Z","caller":"traceutil/trace.go:171","msg":"trace[196648634] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"102.983101ms","start":"2026-09-07T18:31:08.059847Z","end":"2026-09-07T18:31:08.16283Z","steps":["trace[196648634] 'process raft request'  (duration: 102.872988ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:08.162905Z","caller":"traceutil/trace.go:171","msg":"trace[146774238] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"203.8421ms","start":"2026-09-07T18:31:07.959021Z","end":"2026-09-07T18:31:08.162864Z","steps":["trace[146774238] 'process raft request'  (duration: 203.594023ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:22.633382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.22772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-07T18:31:22.633468Z","caller":"traceutil/trace.go:171","msg":"trace[1406951622] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:5576; }","duration":"289.366734ms","start":"2026-09-07T18:31:22.344084Z","end":"2026-09-07T18:31:22.633451Z","steps":["trace[1406951622] 'count revisions from in-memory index tree'  (duration: 289.151378ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:22.633375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.513397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:31:22.633649Z","caller":"traceutil/trace.go:171","msg":"trace[1801864963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5576; }","duration":"259.844588ms","start":"2026-09-07T18:31:22.373784Z","end":"2026-09-07T18:31:22.633629Z","steps":["trace[1801864963] 'range keys from in-memory index tree'  (duration: 259.383283ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:22.71393Z","caller":"traceutil/trace.go:171","msg":"trace[1357082864] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"292.923518ms","start":"2026-09-07T18:31:22.420985Z","end":"2026-09-07T18:31:22.713909Z","steps":["trace[1357082864] 'process raft request'  (duration: 292.777544ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:22.714043Z","caller":"traceutil/trace.go:171","msg":"trace[972651325] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5789; }","duration":"205.697714ms","start":"2026-09-07T18:31:22.508261Z","end":"2026-09-07T18:31:22.713958Z","steps":["trace[972651325] 'read index received'  (duration: 205.678744ms)","trace[972651325] 'applied index is now lower than readState.Index'  (duration: 14.79µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:31:22.714398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.116617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-hxjkv\" ","response":"range_response_count:1 size:7817"}
{"level":"info","ts":"2026-09-07T18:31:22.714698Z","caller":"traceutil/trace.go:171","msg":"trace[1767083906] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-hxjkv; range_end:; response_count:1; response_revision:5577; }","duration":"206.488807ms","start":"2026-09-07T18:31:22.508183Z","end":"2026-09-07T18:31:22.714671Z","steps":["trace[1767083906] 'agreement among raft nodes before linearized reading'  (duration: 205.987122ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:23.01119Z","caller":"traceutil/trace.go:171","msg":"trace[2013744194] linearizableReadLoop","detail":"{readStateIndex:5790; appliedIndex:5789; }","duration":"297.019017ms","start":"2026-09-07T18:31:22.71413Z","end":"2026-09-07T18:31:23.011149Z","steps":["trace[2013744194] 'read index received'  (duration: 294.251537ms)","trace[2013744194] 'applied index is now lower than readState.Index'  (duration: 2.76619ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:31:23.011242Z","caller":"traceutil/trace.go:171","msg":"trace[584451164] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"448.99441ms","start":"2026-09-07T18:31:22.562218Z","end":"2026-09-07T18:31:23.011212Z","steps":["trace[584451164] 'process raft request'  (duration: 446.069595ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:23.011431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:22.562158Z","time spent":"449.172367ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:5536 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-07T18:31:23.011739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.913338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-07T18:31:23.011794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.179723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:31:23.011814Z","caller":"traceutil/trace.go:171","msg":"trace[1171377249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5578; }","duration":"377.033972ms","start":"2026-09-07T18:31:22.634759Z","end":"2026-09-07T18:31:23.011793Z","steps":["trace[1171377249] 'agreement among raft nodes before linearized reading'  (duration: 376.533917ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:23.011843Z","caller":"traceutil/trace.go:171","msg":"trace[1778135415] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5578; }","duration":"422.288026ms","start":"2026-09-07T18:31:22.589541Z","end":"2026-09-07T18:31:23.011829Z","steps":["trace[1778135415] 'agreement among raft nodes before linearized reading'  (duration: 421.903614ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:23.011907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:22.589517Z","time spent":"422.358328ms","remote":"127.0.0.1:50328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "}
{"level":"warn","ts":"2026-09-07T18:31:23.012082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:31:22.634742Z","time spent":"377.106604ms","remote":"127.0.0.1:58722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-09-07T18:31:23.024287Z","caller":"traceutil/trace.go:171","msg":"trace[1960844802] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"298.921993ms","start":"2026-09-07T18:31:22.725342Z","end":"2026-09-07T18:31:23.024264Z","steps":["trace[1960844802] 'process raft request'  (duration: 298.650024ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:23.024473Z","caller":"traceutil/trace.go:171","msg":"trace[1884577294] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"102.600181ms","start":"2026-09-07T18:31:22.921845Z","end":"2026-09-07T18:31:23.024445Z","steps":["trace[1884577294] 'process raft request'  (duration: 102.332183ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:23.024558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.746569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-09-07T18:31:23.024609Z","caller":"traceutil/trace.go:171","msg":"trace[579208006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5580; }","duration":"224.850071ms","start":"2026-09-07T18:31:22.799745Z","end":"2026-09-07T18:31:23.024595Z","steps":["trace[579208006] 'agreement among raft nodes before linearized reading'  (duration: 224.664766ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:31.47596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.202818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c550af8-431e-49bd-aa87-b7dca825eaa4\" ","response":"range_response_count:1 size:3645"}
{"level":"info","ts":"2026-09-07T18:31:31.476005Z","caller":"traceutil/trace.go:171","msg":"trace[502004055] linearizableReadLoop","detail":"{readStateIndex:5994; appliedIndex:5993; }","duration":"103.676911ms","start":"2026-09-07T18:31:31.372302Z","end":"2026-09-07T18:31:31.475979Z","steps":["trace[502004055] 'read index received'  (duration: 86.230884ms)","trace[502004055] 'applied index is now lower than readState.Index'  (duration: 17.444067ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:31:31.476055Z","caller":"traceutil/trace.go:171","msg":"trace[1348145294] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c550af8-431e-49bd-aa87-b7dca825eaa4; range_end:; response_count:1; response_revision:5777; }","duration":"196.345022ms","start":"2026-09-07T18:31:31.279688Z","end":"2026-09-07T18:31:31.476033Z","steps":["trace[1348145294] 'range keys from in-memory index tree'  (duration: 195.988472ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:31:31.476012Z","caller":"traceutil/trace.go:171","msg":"trace[1417632689] transaction","detail":"{read_only:false; response_revision:5778; number_of_response:1; }","duration":"188.111654ms","start":"2026-09-07T18:31:31.287873Z","end":"2026-09-07T18:31:31.475985Z","steps":["trace[1417632689] 'process raft request'  (duration: 170.703988ms)","trace[1417632689] 'compare'  (duration: 16.971444ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:31:31.476158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.844066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:31:31.476196Z","caller":"traceutil/trace.go:171","msg":"trace[693814453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5778; }","duration":"103.938979ms","start":"2026-09-07T18:31:31.372247Z","end":"2026-09-07T18:31:31.476186Z","steps":["trace[693814453] 'agreement among raft nodes before linearized reading'  (duration: 103.833716ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:31:31.696669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.773478ms","expected-duration":"100ms","prefix":"","request":"header:<ID:11420179214283941478 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18d31d7acc29eca6\" mod_revision:5741 > success:<request_put:<key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18d31d7acc29eca6\" value_size:897 lease:2196807177429164557 >> failure:<request_range:<key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18d31d7acc29eca6\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T18:31:31.69679Z","caller":"traceutil/trace.go:171","msg":"trace[1583982458] transaction","detail":"{read_only:false; response_revision:5779; number_of_response:1; }","duration":"174.473258ms","start":"2026-09-07T18:31:31.52229Z","end":"2026-09-07T18:31:31.696764Z","steps":["trace[1583982458] 'process raft request'  (duration: 64.38942ms)","trace[1583982458] 'compare'  (duration: 109.617144ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:31:37.134852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2976}
{"level":"info","ts":"2026-09-07T18:31:37.216566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2976,"took":"79.557651ms","hash":3713140204,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":32202752,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-07T18:31:37.216631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3713140204,"revision":2976,"compact-revision":-1}
{"level":"info","ts":"2026-09-07T18:32:23.126358Z","caller":"traceutil/trace.go:171","msg":"trace[1562564973] linearizableReadLoop","detail":"{readStateIndex:6492; appliedIndex:6491; }","duration":"102.466579ms","start":"2026-09-07T18:32:23.023858Z","end":"2026-09-07T18:32:23.126324Z","steps":["trace[1562564973] 'read index received'  (duration: 102.235742ms)","trace[1562564973] 'applied index is now lower than readState.Index'  (duration: 229.597µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:32:23.126569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.668204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:32:23.126683Z","caller":"traceutil/trace.go:171","msg":"trace[58017040] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:6263; }","duration":"102.839549ms","start":"2026-09-07T18:32:23.023817Z","end":"2026-09-07T18:32:23.126656Z","steps":["trace[58017040] 'agreement among raft nodes before linearized reading'  (duration: 102.629523ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:32:23.127059Z","caller":"traceutil/trace.go:171","msg":"trace[1406277173] transaction","detail":"{read_only:false; response_revision:6263; number_of_response:1; }","duration":"142.618836ms","start":"2026-09-07T18:32:22.983775Z","end":"2026-09-07T18:32:23.126394Z","steps":["trace[1406277173] 'process raft request'  (duration: 142.289016ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:36:37.144207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5881}
{"level":"info","ts":"2026-09-07T18:36:37.249187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5881,"took":"102.242579ms","hash":1432492244,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":26148864,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-07T18:36:37.249272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1432492244,"revision":5881,"compact-revision":2976}
{"level":"info","ts":"2026-09-07T18:37:45.794267Z","caller":"traceutil/trace.go:171","msg":"trace[1687920392] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"149.036294ms","start":"2026-09-07T18:37:45.6452Z","end":"2026-09-07T18:37:45.794236Z","steps":["trace[1687920392] 'process raft request'  (duration: 148.835309ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:37:45.839536Z","caller":"traceutil/trace.go:171","msg":"trace[367967279] linearizableReadLoop","detail":"{readStateIndex:8934; appliedIndex:8932; }","duration":"118.947097ms","start":"2026-09-07T18:37:45.720563Z","end":"2026-09-07T18:37:45.83951Z","steps":["trace[367967279] 'read index received'  (duration: 73.418877ms)","trace[367967279] 'applied index is now lower than readState.Index'  (duration: 45.52673ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:37:45.839566Z","caller":"traceutil/trace.go:171","msg":"trace[1819454940] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"117.942321ms","start":"2026-09-07T18:37:45.721593Z","end":"2026-09-07T18:37:45.839535Z","steps":["trace[1819454940] 'process raft request'  (duration: 117.737355ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:37:45.856757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.16805ms","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-07T18:37:45.856854Z","caller":"traceutil/trace.go:171","msg":"trace[1856276976] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8628; }","duration":"136.321444ms","start":"2026-09-07T18:37:45.720512Z","end":"2026-09-07T18:37:45.856833Z","steps":["trace[1856276976] 'agreement among raft nodes before linearized reading'  (duration: 119.112823ms)","trace[1856276976] 'count revisions from in-memory index tree'  (duration: 17.037236ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:37:46.560837Z","caller":"traceutil/trace.go:171","msg":"trace[1285311460] linearizableReadLoop","detail":"{readStateIndex:8939; appliedIndex:8938; }","duration":"291.883253ms","start":"2026-09-07T18:37:46.268929Z","end":"2026-09-07T18:37:46.560812Z","steps":["trace[1285311460] 'read index received'  (duration: 291.698907ms)","trace[1285311460] 'applied index is now lower than readState.Index'  (duration: 182.756µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:37:46.560902Z","caller":"traceutil/trace.go:171","msg":"trace[722334757] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"312.978698ms","start":"2026-09-07T18:37:46.247885Z","end":"2026-09-07T18:37:46.560864Z","steps":["trace[722334757] 'process raft request'  (duration: 312.778823ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:37:46.561002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.951847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:37:46.56104Z","caller":"traceutil/trace.go:171","msg":"trace[1487047748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8632; }","duration":"188.05415ms","start":"2026-09-07T18:37:46.372976Z","end":"2026-09-07T18:37:46.56103Z","steps":["trace[1487047748] 'agreement among raft nodes before linearized reading'  (duration: 187.981978ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:37:46.561078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:37:46.247855Z","time spent":"313.127492ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:8621 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-09-07T18:37:46.561249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.277543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jjzhd\" ","response":"range_response_count:1 size:6520"}
{"level":"info","ts":"2026-09-07T18:37:46.561381Z","caller":"traceutil/trace.go:171","msg":"trace[1746223041] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jjzhd; range_end:; response_count:1; response_revision:8632; }","duration":"292.489519ms","start":"2026-09-07T18:37:46.268861Z","end":"2026-09-07T18:37:46.561351Z","steps":["trace[1746223041] 'agreement among raft nodes before linearized reading'  (duration: 292.101329ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:37:47.255045Z","caller":"traceutil/trace.go:171","msg":"trace[1902825699] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"107.149ms","start":"2026-09-07T18:37:47.147337Z","end":"2026-09-07T18:37:47.254486Z","steps":["trace[1902825699] 'process raft request'  (duration: 106.923435ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:38:12.118207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.008518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:38:12.118524Z","caller":"traceutil/trace.go:171","msg":"trace[1935662400] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8820; }","duration":"113.376508ms","start":"2026-09-07T18:38:12.005119Z","end":"2026-09-07T18:38:12.118496Z","steps":["trace[1935662400] 'count revisions from in-memory index tree'  (duration: 112.946567ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:38:12.118429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.164847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"}
{"level":"info","ts":"2026-09-07T18:38:12.118643Z","caller":"traceutil/trace.go:171","msg":"trace[1519957574] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:8820; }","duration":"115.432944ms","start":"2026-09-07T18:38:12.003188Z","end":"2026-09-07T18:38:12.118621Z","steps":["trace[1519957574] 'range keys from in-memory index tree'  (duration: 114.976142ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:38:15.402343Z","caller":"traceutil/trace.go:171","msg":"trace[1882492632] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"139.084428ms","start":"2026-09-07T18:38:15.263226Z","end":"2026-09-07T18:38:15.402311Z","steps":["trace[1882492632] 'process raft request'  (duration: 138.82342ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:38:16.268306Z","caller":"traceutil/trace.go:171","msg":"trace[2100025859] linearizableReadLoop","detail":"{readStateIndex:9159; appliedIndex:9158; }","duration":"108.466416ms","start":"2026-09-07T18:38:16.159808Z","end":"2026-09-07T18:38:16.268274Z","steps":["trace[2100025859] 'read index received'  (duration: 108.164848ms)","trace[2100025859] 'applied index is now lower than readState.Index'  (duration: 296.618µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:38:16.268366Z","caller":"traceutil/trace.go:171","msg":"trace[1879130516] transaction","detail":"{read_only:false; response_revision:8844; number_of_response:1; }","duration":"124.155867ms","start":"2026-09-07T18:38:16.144166Z","end":"2026-09-07T18:38:16.268322Z","steps":["trace[1879130516] 'process raft request'  (duration: 123.89006ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:38:16.268613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.759254ms","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-07T18:38:16.268699Z","caller":"traceutil/trace.go:171","msg":"trace[898821912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8844; }","duration":"108.948579ms","start":"2026-09-07T18:38:16.15973Z","end":"2026-09-07T18:38:16.268678Z","steps":["trace[898821912] 'agreement among raft nodes before linearized reading'  (duration: 108.659171ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:38:22.380134Z","caller":"traceutil/trace.go:171","msg":"trace[1734537970] transaction","detail":"{read_only:false; response_revision:8877; number_of_response:1; }","duration":"136.175869ms","start":"2026-09-07T18:38:22.243933Z","end":"2026-09-07T18:38:22.380109Z","steps":["trace[1734537970] 'process raft request'  (duration: 135.933313ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:38:28.883045Z","caller":"traceutil/trace.go:171","msg":"trace[417032062] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"122.677758ms","start":"2026-09-07T18:38:28.760339Z","end":"2026-09-07T18:38:28.883016Z","steps":["trace[417032062] 'process raft request'  (duration: 122.374029ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:38:29.018883Z","caller":"traceutil/trace.go:171","msg":"trace[1095294152] linearizableReadLoop","detail":"{readStateIndex:9275; appliedIndex:9274; }","duration":"128.450023ms","start":"2026-09-07T18:38:28.890401Z","end":"2026-09-07T18:38:29.018851Z","steps":["trace[1095294152] 'read index received'  (duration: 102.458226ms)","trace[1095294152] 'applied index is now lower than readState.Index'  (duration: 25.990697ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:38:29.019007Z","caller":"traceutil/trace.go:171","msg":"trace[922840658] transaction","detail":"{read_only:false; response_revision:8955; number_of_response:1; }","duration":"129.314117ms","start":"2026-09-07T18:38:28.88965Z","end":"2026-09-07T18:38:29.018964Z","steps":["trace[922840658] 'process raft request'  (duration: 103.145855ms)","trace[922840658] 'compare'  (duration: 25.880594ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:38:29.01952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.094319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228416"}
{"level":"info","ts":"2026-09-07T18:38:29.01961Z","caller":"traceutil/trace.go:171","msg":"trace[1745357352] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8955; }","duration":"129.229843ms","start":"2026-09-07T18:38:28.890361Z","end":"2026-09-07T18:38:29.019591Z","steps":["trace[1745357352] 'agreement among raft nodes before linearized reading'  (duration: 128.698659ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:38:42.910263Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b0.wal"}
{"level":"info","ts":"2026-09-07T18:39:38.431042Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-07T18:39:38.43691Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-09-07T18:39:38.437007Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-09-07T18:39:49.060688Z","caller":"traceutil/trace.go:171","msg":"trace[1069499125] linearizableReadLoop","detail":"{readStateIndex:10065; appliedIndex:10063; }","duration":"181.30745ms","start":"2026-09-07T18:39:48.879364Z","end":"2026-09-07T18:39:49.060671Z","steps":["trace[1069499125] 'read index received'  (duration: 14.82528ms)","trace[1069499125] 'applied index is now lower than readState.Index'  (duration: 166.48135ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:39:49.060784Z","caller":"traceutil/trace.go:171","msg":"trace[1819293935] transaction","detail":"{read_only:false; response_revision:9725; number_of_response:1; }","duration":"186.418169ms","start":"2026-09-07T18:39:48.874338Z","end":"2026-09-07T18:39:49.060757Z","steps":["trace[1819293935] 'process raft request'  (duration: 186.262334ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:39:49.060839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.462255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"}
{"level":"info","ts":"2026-09-07T18:39:49.060862Z","caller":"traceutil/trace.go:171","msg":"trace[747058493] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9725; }","duration":"181.513167ms","start":"2026-09-07T18:39:48.879339Z","end":"2026-09-07T18:39:49.060852Z","steps":["trace[747058493] 'agreement among raft nodes before linearized reading'  (duration: 181.425174ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:39:49.252583Z","caller":"traceutil/trace.go:171","msg":"trace[683867056] transaction","detail":"{read_only:false; response_revision:9727; number_of_response:1; }","duration":"109.819491ms","start":"2026-09-07T18:39:49.142717Z","end":"2026-09-07T18:39:49.252537Z","steps":["trace[683867056] 'process raft request'  (duration: 74.554958ms)","trace[683867056] 'compare'  (duration: 34.85585ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:41:37.151786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8036}
{"level":"info","ts":"2026-09-07T18:41:37.228452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8036,"took":"74.057497ms","hash":1667715269,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":28422144,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-07T18:41:37.228526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1667715269,"revision":8036,"compact-revision":5881}
{"level":"info","ts":"2026-09-07T18:43:28.160187Z","caller":"traceutil/trace.go:171","msg":"trace[1122280214] transaction","detail":"{read_only:false; response_revision:11539; number_of_response:1; }","duration":"168.027513ms","start":"2026-09-07T18:43:27.992136Z","end":"2026-09-07T18:43:28.160163Z","steps":["trace[1122280214] 'process raft request'  (duration: 167.854308ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:43:28.185717Z","caller":"traceutil/trace.go:171","msg":"trace[495479208] transaction","detail":"{read_only:false; response_revision:11540; number_of_response:1; }","duration":"132.035439ms","start":"2026-09-07T18:43:28.053651Z","end":"2026-09-07T18:43:28.185687Z","steps":["trace[495479208] 'process raft request'  (duration: 131.797803ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:43:28.185969Z","caller":"traceutil/trace.go:171","msg":"trace[150412394] transaction","detail":"{read_only:false; response_revision:11541; number_of_response:1; }","duration":"116.341034ms","start":"2026-09-07T18:43:28.069558Z","end":"2026-09-07T18:43:28.185899Z","steps":["trace[150412394] 'process raft request'  (duration: 116.037636ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:43:28.438533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.76488ms","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-07T18:43:28.43861Z","caller":"traceutil/trace.go:171","msg":"trace[2107120315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11541; }","duration":"184.886974ms","start":"2026-09-07T18:43:28.253704Z","end":"2026-09-07T18:43:28.438591Z","steps":["trace[2107120315] 'range keys from in-memory index tree'  (duration: 184.542182ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:43:28.438781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.579843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-07T18:43:28.438856Z","caller":"traceutil/trace.go:171","msg":"trace[1400609144] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11541; }","duration":"165.705907ms","start":"2026-09-07T18:43:28.27313Z","end":"2026-09-07T18:43:28.438835Z","steps":["trace[1400609144] 'range keys from in-memory index tree'  (duration: 165.326006ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:46:37.158442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10501}
{"level":"info","ts":"2026-09-07T18:46:37.245917Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10501,"took":"84.973294ms","hash":707949679,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":27308032,"current-db-size-in-use":"27 MB"}
{"level":"info","ts":"2026-09-07T18:46:37.245986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":707949679,"revision":10501,"compact-revision":8036}
{"level":"warn","ts":"2026-09-07T18:49:57.337423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.235165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"info","ts":"2026-09-07T18:49:57.337511Z","caller":"traceutil/trace.go:171","msg":"trace[1566547764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14154; }","duration":"132.369608ms","start":"2026-09-07T18:49:57.205124Z","end":"2026-09-07T18:49:57.337494Z","steps":["trace[1566547764] 'range keys from in-memory index tree'  (duration: 132.036679ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:49:57.337737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.989329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-09-07T18:49:57.337774Z","caller":"traceutil/trace.go:171","msg":"trace[887721711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14154; }","duration":"111.02739ms","start":"2026-09-07T18:49:57.226734Z","end":"2026-09-07T18:49:57.337762Z","steps":["trace[887721711] 'range keys from in-memory index tree'  (duration: 110.869906ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:49:57.496088Z","caller":"traceutil/trace.go:171","msg":"trace[351669092] transaction","detail":"{read_only:false; response_revision:14155; number_of_response:1; }","duration":"146.245221ms","start":"2026-09-07T18:49:57.349812Z","end":"2026-09-07T18:49:57.496057Z","steps":["trace[351669092] 'process raft request'  (duration: 146.018305ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:49:57.533496Z","caller":"traceutil/trace.go:171","msg":"trace[399790825] linearizableReadLoop","detail":"{readStateIndex:14644; appliedIndex:14642; }","duration":"161.316989ms","start":"2026-09-07T18:49:57.372161Z","end":"2026-09-07T18:49:57.533478Z","steps":["trace[399790825] 'read index received'  (duration: 123.669277ms)","trace[399790825] 'applied index is now lower than readState.Index'  (duration: 37.647092ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:49:57.5336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.437422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:49:57.533579Z","caller":"traceutil/trace.go:171","msg":"trace[824336623] transaction","detail":"{read_only:false; response_revision:14156; number_of_response:1; }","duration":"183.552894ms","start":"2026-09-07T18:49:57.349989Z","end":"2026-09-07T18:49:57.533542Z","steps":["trace[824336623] 'process raft request'  (duration: 183.380859ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:49:57.533629Z","caller":"traceutil/trace.go:171","msg":"trace[745682090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14156; }","duration":"161.514764ms","start":"2026-09-07T18:49:57.372104Z","end":"2026-09-07T18:49:57.533619Z","steps":["trace[745682090] 'agreement among raft nodes before linearized reading'  (duration: 161.452092ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:49:57.929849Z","caller":"traceutil/trace.go:171","msg":"trace[1857124154] transaction","detail":"{read_only:false; response_revision:14157; number_of_response:1; }","duration":"265.51666ms","start":"2026-09-07T18:49:57.664306Z","end":"2026-09-07T18:49:57.929822Z","steps":["trace[1857124154] 'process raft request'  (duration: 265.341575ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:49:57.940607Z","caller":"traceutil/trace.go:171","msg":"trace[411723377] transaction","detail":"{read_only:false; response_revision:14158; number_of_response:1; }","duration":"273.292766ms","start":"2026-09-07T18:49:57.667284Z","end":"2026-09-07T18:49:57.940577Z","steps":["trace[411723377] 'process raft request'  (duration: 273.024649ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:49:57.940646Z","caller":"traceutil/trace.go:171","msg":"trace[505778907] linearizableReadLoop","detail":"{readStateIndex:14648; appliedIndex:14644; }","duration":"221.797982ms","start":"2026-09-07T18:49:57.718815Z","end":"2026-09-07T18:49:57.940613Z","steps":["trace[505778907] 'read index received'  (duration: 210.802653ms)","trace[505778907] 'applied index is now lower than readState.Index'  (duration: 10.994169ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:49:57.940713Z","caller":"traceutil/trace.go:171","msg":"trace[1545236791] transaction","detail":"{read_only:false; response_revision:14159; number_of_response:1; }","duration":"259.756144ms","start":"2026-09-07T18:49:57.680927Z","end":"2026-09-07T18:49:57.940683Z","steps":["trace[1545236791] 'process raft request'  (duration: 259.555199ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:49:57.940807Z","caller":"traceutil/trace.go:171","msg":"trace[439563528] transaction","detail":"{read_only:false; response_revision:14160; number_of_response:1; }","duration":"235.645195ms","start":"2026-09-07T18:49:57.705151Z","end":"2026-09-07T18:49:57.940796Z","steps":["trace[439563528] 'process raft request'  (duration: 235.406518ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:49:57.940823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.972418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-07T18:49:57.940876Z","caller":"traceutil/trace.go:171","msg":"trace[1409713852] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14160; }","duration":"222.05533ms","start":"2026-09-07T18:49:57.718801Z","end":"2026-09-07T18:49:57.940856Z","steps":["trace[1409713852] 'agreement among raft nodes before linearized reading'  (duration: 221.943277ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:51:37.164794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12782}
{"level":"info","ts":"2026-09-07T18:51:37.247559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12782,"took":"79.138439ms","hash":4201814503,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":30666752,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-07T18:51:37.247641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4201814503,"revision":12782,"compact-revision":10501}
{"level":"info","ts":"2026-09-07T18:51:43.328295Z","caller":"traceutil/trace.go:171","msg":"trace[982249940] linearizableReadLoop","detail":"{readStateIndex:15910; appliedIndex:15909; }","duration":"223.353265ms","start":"2026-09-07T18:51:43.104912Z","end":"2026-09-07T18:51:43.328265Z","steps":["trace[982249940] 'read index received'  (duration: 223.206901ms)","trace[982249940] 'applied index is now lower than readState.Index'  (duration: 144.964µs)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:51:43.32847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.546511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-07T18:51:43.328433Z","caller":"traceutil/trace.go:171","msg":"trace[1371185998] transaction","detail":"{read_only:false; response_revision:15385; number_of_response:1; }","duration":"258.846971ms","start":"2026-09-07T18:51:43.069555Z","end":"2026-09-07T18:51:43.328402Z","steps":["trace[1371185998] 'process raft request'  (duration: 258.526892ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:51:43.32854Z","caller":"traceutil/trace.go:171","msg":"trace[650809819] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15385; }","duration":"223.661884ms","start":"2026-09-07T18:51:43.104841Z","end":"2026-09-07T18:51:43.328503Z","steps":["trace[650809819] 'agreement among raft nodes before linearized reading'  (duration: 223.564121ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:51:43.523743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.86477ms","expected-duration":"100ms","prefix":"","request":"header:<ID:11420179214283968640 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:15378 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-07T18:51:43.523891Z","caller":"traceutil/trace.go:171","msg":"trace[496907723] linearizableReadLoop","detail":"{readStateIndex:15911; appliedIndex:15910; }","duration":"195.494043ms","start":"2026-09-07T18:51:43.328375Z","end":"2026-09-07T18:51:43.523869Z","steps":["trace[496907723] 'read index received'  (duration: 68.313423ms)","trace[496907723] 'applied index is now lower than readState.Index'  (duration: 127.17851ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:51:43.52404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.8297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-07T18:51:43.524131Z","caller":"traceutil/trace.go:171","msg":"trace[1244992234] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15386; }","duration":"319.956663ms","start":"2026-09-07T18:51:43.204154Z","end":"2026-09-07T18:51:43.524111Z","steps":["trace[1244992234] 'agreement among raft nodes before linearized reading'  (duration: 319.732827ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:51:43.524171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:51:43.204141Z","time spent":"320.020556ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-09-07T18:51:43.524344Z","caller":"traceutil/trace.go:171","msg":"trace[608006930] transaction","detail":"{read_only:false; response_revision:15386; number_of_response:1; }","duration":"258.550443ms","start":"2026-09-07T18:51:43.265706Z","end":"2026-09-07T18:51:43.524256Z","steps":["trace[608006930] 'process raft request'  (duration: 131.081404ms)","trace[608006930] 'compare'  (duration: 126.572832ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T18:51:43.524654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.189708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"}
{"level":"info","ts":"2026-09-07T18:51:43.524739Z","caller":"traceutil/trace.go:171","msg":"trace[1999192728] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15386; }","duration":"418.305133ms","start":"2026-09-07T18:51:43.106417Z","end":"2026-09-07T18:51:43.524722Z","steps":["trace[1999192728] 'agreement among raft nodes before linearized reading'  (duration: 417.992054ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:51:43.524783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:51:43.106404Z","time spent":"418.366464ms","remote":"127.0.0.1:58892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":688,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "}
{"level":"warn","ts":"2026-09-07T18:51:43.524836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.16666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"}
{"level":"info","ts":"2026-09-07T18:51:43.52496Z","caller":"traceutil/trace.go:171","msg":"trace[817661304] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15386; }","duration":"379.374337ms","start":"2026-09-07T18:51:43.145568Z","end":"2026-09-07T18:51:43.524942Z","steps":["trace[817661304] 'agreement among raft nodes before linearized reading'  (duration: 378.43587ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:51:43.525235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T18:51:43.145547Z","time spent":"379.50934ms","remote":"127.0.0.1:58958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "}
{"level":"info","ts":"2026-09-07T18:56:37.171262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15356}
{"level":"info","ts":"2026-09-07T18:56:37.276297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15356,"took":"102.699409ms","hash":3335167067,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":32149504,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-07T18:56:37.276372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3335167067,"revision":15356,"compact-revision":12782}
{"level":"info","ts":"2026-09-07T18:57:22.605808Z","caller":"traceutil/trace.go:171","msg":"trace[2078426327] transaction","detail":"{read_only:false; response_revision:17929; number_of_response:1; }","duration":"188.816587ms","start":"2026-09-07T18:57:22.416948Z","end":"2026-09-07T18:57:22.605765Z","steps":["trace[2078426327] 'process raft request'  (duration: 188.537069ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:57:22.95136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.379638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T18:57:22.951427Z","caller":"traceutil/trace.go:171","msg":"trace[724078002] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:17930; }","duration":"131.503361ms","start":"2026-09-07T18:57:22.819907Z","end":"2026-09-07T18:57:22.95141Z","steps":["trace[724078002] 'count revisions from in-memory index tree'  (duration: 131.249344ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:57:22.951466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.275845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-xqw8k\" ","response":"range_response_count:1 size:20752"}
{"level":"info","ts":"2026-09-07T18:57:22.951523Z","caller":"traceutil/trace.go:171","msg":"trace[1815780053] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-xqw8k; range_end:; response_count:1; response_revision:17930; }","duration":"227.389699ms","start":"2026-09-07T18:57:22.724117Z","end":"2026-09-07T18:57:22.951507Z","steps":["trace[1815780053] 'range keys from in-memory index tree'  (duration: 227.044919ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:57:22.951503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.64299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-07T18:57:22.951584Z","caller":"traceutil/trace.go:171","msg":"trace[607021235] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:17930; }","duration":"121.778844ms","start":"2026-09-07T18:57:22.829785Z","end":"2026-09-07T18:57:22.951564Z","steps":["trace[607021235] 'count revisions from in-memory index tree'  (duration: 121.556448ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T18:57:23.191035Z","caller":"traceutil/trace.go:171","msg":"trace[549807729] linearizableReadLoop","detail":"{readStateIndex:18540; appliedIndex:18539; }","duration":"118.706481ms","start":"2026-09-07T18:57:23.072268Z","end":"2026-09-07T18:57:23.190974Z","steps":["trace[549807729] 'read index received'  (duration: 118.421823ms)","trace[549807729] 'applied index is now lower than readState.Index'  (duration: 283.208µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T18:57:23.191138Z","caller":"traceutil/trace.go:171","msg":"trace[1761022675] transaction","detail":"{read_only:false; response_revision:17932; number_of_response:1; }","duration":"216.946989ms","start":"2026-09-07T18:57:22.974137Z","end":"2026-09-07T18:57:23.191084Z","steps":["trace[1761022675] 'process raft request'  (duration: 216.559058ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T18:57:23.191192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.898369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T18:57:23.191271Z","caller":"traceutil/trace.go:171","msg":"trace[2117076505] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:17932; }","duration":"119.036292ms","start":"2026-09-07T18:57:23.072215Z","end":"2026-09-07T18:57:23.191251Z","steps":["trace[2117076505] 'agreement among raft nodes before linearized reading'  (duration: 118.895127ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:00:04.066434Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"30b39d71f9e69e7c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-07T19:00:04.073696Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-09-07T19:00:04.07379Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-09-07T19:01:37.180113Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17545}
{"level":"info","ts":"2026-09-07T19:01:37.287785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17545,"took":"103.749289ms","hash":221745721,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":34926592,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-09-07T19:01:37.287855Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":221745721,"revision":17545,"compact-revision":15356}
{"level":"info","ts":"2026-09-07T19:02:35.014281Z","caller":"traceutil/trace.go:171","msg":"trace[83195631] transaction","detail":"{read_only:false; response_revision:20442; number_of_response:1; }","duration":"134.962514ms","start":"2026-09-07T19:02:34.879296Z","end":"2026-09-07T19:02:35.014258Z","steps":["trace[83195631] 'process raft request'  (duration: 134.915283ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:35.014325Z","caller":"traceutil/trace.go:171","msg":"trace[145323644] transaction","detail":"{read_only:false; response_revision:20441; number_of_response:1; }","duration":"158.892662ms","start":"2026-09-07T19:02:34.855397Z","end":"2026-09-07T19:02:35.01429Z","steps":["trace[145323644] 'process raft request'  (duration: 152.867503ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:35.014362Z","caller":"traceutil/trace.go:171","msg":"trace[496796111] linearizableReadLoop","detail":"{readStateIndex:21129; appliedIndex:21128; }","duration":"139.677674ms","start":"2026-09-07T19:02:34.874632Z","end":"2026-09-07T19:02:35.01431Z","steps":["trace[496796111] 'read index received'  (duration: 133.664795ms)","trace[496796111] 'applied index is now lower than readState.Index'  (duration: 6.000968ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:02:35.014644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.939681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T19:02:35.014679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.181562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"}
{"level":"info","ts":"2026-09-07T19:02:35.014715Z","caller":"traceutil/trace.go:171","msg":"trace[620139130] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20442; }","duration":"129.242214ms","start":"2026-09-07T19:02:34.88546Z","end":"2026-09-07T19:02:35.014702Z","steps":["trace[620139130] 'agreement among raft nodes before linearized reading'  (duration: 129.045148ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:35.014724Z","caller":"traceutil/trace.go:171","msg":"trace[1355615786] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:20442; }","duration":"140.125826ms","start":"2026-09-07T19:02:34.874576Z","end":"2026-09-07T19:02:35.014701Z","steps":["trace[1355615786] 'agreement among raft nodes before linearized reading'  (duration: 139.843888ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:02:40.519108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.464461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-07T19:02:40.519217Z","caller":"traceutil/trace.go:171","msg":"trace[1756028477] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:20469; }","duration":"102.658078ms","start":"2026-09-07T19:02:40.416538Z","end":"2026-09-07T19:02:40.519196Z","steps":["trace[1756028477] 'count revisions from in-memory index tree'  (duration: 102.321008ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:02:40.519417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.407283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:02:40.519527Z","caller":"traceutil/trace.go:171","msg":"trace[695285228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20469; }","duration":"146.582297ms","start":"2026-09-07T19:02:40.372917Z","end":"2026-09-07T19:02:40.519499Z","steps":["trace[695285228] 'range keys from in-memory index tree'  (duration: 146.296649ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:40.704519Z","caller":"traceutil/trace.go:171","msg":"trace[1121758682] transaction","detail":"{read_only:false; response_revision:20470; number_of_response:1; }","duration":"120.780334ms","start":"2026-09-07T19:02:40.583712Z","end":"2026-09-07T19:02:40.704493Z","steps":["trace[1121758682] 'process raft request'  (duration: 120.62165ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:51.145819Z","caller":"traceutil/trace.go:171","msg":"trace[1889393631] transaction","detail":"{read_only:false; response_revision:20523; number_of_response:1; }","duration":"161.762836ms","start":"2026-09-07T19:02:50.984037Z","end":"2026-09-07T19:02:51.1458Z","steps":["trace[1889393631] 'process raft request'  (duration: 161.638502ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:56.530206Z","caller":"traceutil/trace.go:171","msg":"trace[1334221624] transaction","detail":"{read_only:false; response_revision:20551; number_of_response:1; }","duration":"155.662067ms","start":"2026-09-07T19:02:56.374482Z","end":"2026-09-07T19:02:56.530144Z","steps":["trace[1334221624] 'process raft request'  (duration: 155.226733ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:57.087149Z","caller":"traceutil/trace.go:171","msg":"trace[746873449] transaction","detail":"{read_only:false; response_revision:20552; number_of_response:1; }","duration":"198.100061ms","start":"2026-09-07T19:02:56.889022Z","end":"2026-09-07T19:02:57.087123Z","steps":["trace[746873449] 'process raft request'  (duration: 197.918796ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:57.434108Z","caller":"traceutil/trace.go:171","msg":"trace[583783363] linearizableReadLoop","detail":"{readStateIndex:21246; appliedIndex:21245; }","duration":"234.342383ms","start":"2026-09-07T19:02:57.19974Z","end":"2026-09-07T19:02:57.434082Z","steps":["trace[583783363] 'read index received'  (duration: 234.057215ms)","trace[583783363] 'applied index is now lower than readState.Index'  (duration: 284.268µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:02:57.434212Z","caller":"traceutil/trace.go:171","msg":"trace[1952617603] transaction","detail":"{read_only:false; response_revision:20553; number_of_response:1; }","duration":"245.062021ms","start":"2026-09-07T19:02:57.189088Z","end":"2026-09-07T19:02:57.43415Z","steps":["trace[1952617603] 'process raft request'  (duration: 244.773523ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:02:57.434422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.583536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"}
{"level":"info","ts":"2026-09-07T19:02:57.43447Z","caller":"traceutil/trace.go:171","msg":"trace[1022593536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20553; }","duration":"222.680329ms","start":"2026-09-07T19:02:57.211778Z","end":"2026-09-07T19:02:57.434459Z","steps":["trace[1022593536] 'agreement among raft nodes before linearized reading'  (duration: 222.486744ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:02:57.434511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.740655ms","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-07T19:02:57.434648Z","caller":"traceutil/trace.go:171","msg":"trace[1634458225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20553; }","duration":"234.939332ms","start":"2026-09-07T19:02:57.199682Z","end":"2026-09-07T19:02:57.434621Z","steps":["trace[1634458225] 'agreement among raft nodes before linearized reading'  (duration: 234.56313ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:02:57.434737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"}
{"level":"info","ts":"2026-09-07T19:02:57.434807Z","caller":"traceutil/trace.go:171","msg":"trace[114001979] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20553; }","duration":"161.364506ms","start":"2026-09-07T19:02:57.273421Z","end":"2026-09-07T19:02:57.434786Z","steps":["trace[114001979] 'agreement among raft nodes before linearized reading'  (duration: 160.936382ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:57.670764Z","caller":"traceutil/trace.go:171","msg":"trace[1768215651] linearizableReadLoop","detail":"{readStateIndex:21249; appliedIndex:21246; }","duration":"103.295417ms","start":"2026-09-07T19:02:57.567443Z","end":"2026-09-07T19:02:57.670738Z","steps":["trace[1768215651] 'read index received'  (duration: 22.659381ms)","trace[1768215651] 'applied index is now lower than readState.Index'  (duration: 80.634886ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:02:57.670957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.480942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-07T19:02:57.670997Z","caller":"traceutil/trace.go:171","msg":"trace[522889546] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:20556; }","duration":"103.557734ms","start":"2026-09-07T19:02:57.567429Z","end":"2026-09-07T19:02:57.670986Z","steps":["trace[522889546] 'agreement among raft nodes before linearized reading'  (duration: 103.4074ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:57.670982Z","caller":"traceutil/trace.go:171","msg":"trace[844548514] transaction","detail":"{read_only:false; response_revision:20555; number_of_response:1; }","duration":"230.807998ms","start":"2026-09-07T19:02:57.440146Z","end":"2026-09-07T19:02:57.670954Z","steps":["trace[844548514] 'process raft request'  (duration: 230.451208ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:02:57.671138Z","caller":"traceutil/trace.go:171","msg":"trace[2017974478] transaction","detail":"{read_only:false; response_revision:20554; number_of_response:1; }","duration":"231.270743ms","start":"2026-09-07T19:02:57.439816Z","end":"2026-09-07T19:02:57.671087Z","steps":["trace[2017974478] 'process raft request'  (duration: 150.268577ms)","trace[2017974478] 'compare'  (duration: 80.315536ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:02:57.671082Z","caller":"traceutil/trace.go:171","msg":"trace[117221065] transaction","detail":"{read_only:false; response_revision:20556; number_of_response:1; }","duration":"230.599483ms","start":"2026-09-07T19:02:57.440437Z","end":"2026-09-07T19:02:57.671037Z","steps":["trace[117221065] 'process raft request'  (duration: 230.261533ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:02:57.995185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.308437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"}
{"level":"info","ts":"2026-09-07T19:02:57.995245Z","caller":"traceutil/trace.go:171","msg":"trace[1964804285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20557; }","duration":"103.438171ms","start":"2026-09-07T19:02:57.891789Z","end":"2026-09-07T19:02:57.995227Z","steps":["trace[1964804285] 'range keys from in-memory index tree'  (duration: 103.146262ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:06:37.204187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20134}
{"level":"info","ts":"2026-09-07T19:06:37.328478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20134,"took":"120.749473ms","hash":1265954854,"current-db-size-bytes":56729600,"current-db-size":"57 MB","current-db-size-in-use-bytes":39886848,"current-db-size-in-use":"40 MB"}
{"level":"info","ts":"2026-09-07T19:06:37.328533Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1265954854,"revision":20134,"compact-revision":17545}
{"level":"info","ts":"2026-09-07T19:06:44.817571Z","caller":"traceutil/trace.go:171","msg":"trace[601594190] transaction","detail":"{read_only:false; response_revision:22988; number_of_response:1; }","duration":"209.920972ms","start":"2026-09-07T19:06:44.607624Z","end":"2026-09-07T19:06:44.817545Z","steps":["trace[601594190] 'process raft request'  (duration: 209.766217ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:11:37.212702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22754}
{"level":"info","ts":"2026-09-07T19:11:37.35852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22754,"took":"141.537988ms","hash":3753103753,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":36528128,"current-db-size-in-use":"36 MB"}
{"level":"info","ts":"2026-09-07T19:11:37.358597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3753103753,"revision":22754,"compact-revision":20134}
{"level":"info","ts":"2026-09-07T19:13:05.247Z","caller":"traceutil/trace.go:171","msg":"trace[1536780241] linearizableReadLoop","detail":"{readStateIndex:26975; appliedIndex:26974; }","duration":"177.914953ms","start":"2026-09-07T19:13:05.069057Z","end":"2026-09-07T19:13:05.246972Z","steps":["trace[1536780241] 'read index received'  (duration: 177.735707ms)","trace[1536780241] 'applied index is now lower than readState.Index'  (duration: 178.216µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:05.247042Z","caller":"traceutil/trace.go:171","msg":"trace[520936040] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"183.109134ms","start":"2026-09-07T19:13:05.063908Z","end":"2026-09-07T19:13:05.247017Z","steps":["trace[520936040] 'process raft request'  (duration: 182.908028ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:05.247425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.358827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:1 size:725"}
{"level":"info","ts":"2026-09-07T19:13:05.24759Z","caller":"traceutil/trace.go:171","msg":"trace[1255726747] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:1; response_revision:26122; }","duration":"178.4591ms","start":"2026-09-07T19:13:05.069008Z","end":"2026-09-07T19:13:05.247467Z","steps":["trace[1255726747] 'agreement among raft nodes before linearized reading'  (duration: 178.101849ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:05.301253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.282404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/nova-bootstrap\" ","response":"range_response_count:1 size:706"}
{"level":"info","ts":"2026-09-07T19:13:05.301365Z","caller":"traceutil/trace.go:171","msg":"trace[184653756] range","detail":"{range_begin:/registry/clusterrolebindings/nova-bootstrap; range_end:; response_count:1; response_revision:26123; }","duration":"222.424087ms","start":"2026-09-07T19:13:05.07892Z","end":"2026-09-07T19:13:05.301344Z","steps":["trace[184653756] 'agreement among raft nodes before linearized reading'  (duration: 222.16252ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:05.301653Z","caller":"traceutil/trace.go:171","msg":"trace[2102845846] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"123.445606ms","start":"2026-09-07T19:13:05.178186Z","end":"2026-09-07T19:13:05.301632Z","steps":["trace[2102845846] 'process raft request'  (duration: 122.713916ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:06.329198Z","caller":"traceutil/trace.go:171","msg":"trace[95968237] linearizableReadLoop","detail":"{readStateIndex:26986; appliedIndex:26985; }","duration":"103.831432ms","start":"2026-09-07T19:13:06.225339Z","end":"2026-09-07T19:13:06.32917Z","steps":["trace[95968237] 'read index received'  (duration: 103.75183ms)","trace[95968237] 'applied index is now lower than readState.Index'  (duration: 78.632µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:06.329339Z","caller":"traceutil/trace.go:171","msg":"trace[619632288] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"119.296915ms","start":"2026-09-07T19:13:06.21Z","end":"2026-09-07T19:13:06.329297Z","steps":["trace[619632288] 'process raft request'  (duration: 118.998957ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:06.329725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.370008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/objectstorage-provisioner-role-binding\" ","response":"range_response_count:1 size:1037"}
{"level":"info","ts":"2026-09-07T19:13:06.329768Z","caller":"traceutil/trace.go:171","msg":"trace[267573824] range","detail":"{range_begin:/registry/clusterrolebindings/objectstorage-provisioner-role-binding; range_end:; response_count:1; response_revision:26132; }","duration":"104.454271ms","start":"2026-09-07T19:13:06.2253Z","end":"2026-09-07T19:13:06.329754Z","steps":["trace[267573824] 'agreement among raft nodes before linearized reading'  (duration: 104.302236ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:06.657099Z","caller":"traceutil/trace.go:171","msg":"trace[592527432] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"122.893941ms","start":"2026-09-07T19:13:06.534174Z","end":"2026-09-07T19:13:06.657068Z","steps":["trace[592527432] 'process raft request'  (duration: 38.548859ms)","trace[592527432] 'compare'  (duration: 84.114034ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:08.068609Z","caller":"traceutil/trace.go:171","msg":"trace[949153847] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"108.390857ms","start":"2026-09-07T19:13:07.960191Z","end":"2026-09-07T19:13:08.068581Z","steps":["trace[949153847] 'process raft request'  (duration: 51.965003ms)","trace[949153847] 'compare'  (duration: 56.242288ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:08.693656Z","caller":"traceutil/trace.go:171","msg":"trace[421830532] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"177.242674ms","start":"2026-09-07T19:13:08.516389Z","end":"2026-09-07T19:13:08.693631Z","steps":["trace[421830532] 'process raft request'  (duration: 177.048788ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:09.708213Z","caller":"traceutil/trace.go:171","msg":"trace[526866367] linearizableReadLoop","detail":"{readStateIndex:27012; appliedIndex:27011; }","duration":"110.043945ms","start":"2026-09-07T19:13:09.598142Z","end":"2026-09-07T19:13:09.708186Z","steps":["trace[526866367] 'read index received'  (duration: 68.196049ms)","trace[526866367] 'applied index is now lower than readState.Index'  (duration: 41.846416ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:09.708294Z","caller":"traceutil/trace.go:171","msg":"trace[1663155933] transaction","detail":"{read_only:false; response_revision:26158; number_of_response:1; }","duration":"117.748849ms","start":"2026-09-07T19:13:09.590511Z","end":"2026-09-07T19:13:09.70826Z","steps":["trace[1663155933] 'process raft request'  (duration: 75.812941ms)","trace[1663155933] 'compare'  (duration: 41.668301ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:13:09.708466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.302493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/rook-ceph-osd-openstack\" ","response":"range_response_count:1 size:722"}
{"level":"info","ts":"2026-09-07T19:13:09.70886Z","caller":"traceutil/trace.go:171","msg":"trace[556880426] range","detail":"{range_begin:/registry/clusterrolebindings/rook-ceph-osd-openstack; range_end:; response_count:1; response_revision:26158; }","duration":"110.725165ms","start":"2026-09-07T19:13:09.598106Z","end":"2026-09-07T19:13:09.708831Z","steps":["trace[556880426] 'agreement among raft nodes before linearized reading'  (duration: 110.163749ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:09.751992Z","caller":"traceutil/trace.go:171","msg":"trace[493761346] transaction","detail":"{read_only:false; response_revision:26159; number_of_response:1; }","duration":"130.789113ms","start":"2026-09-07T19:13:09.621175Z","end":"2026-09-07T19:13:09.751964Z","steps":["trace[493761346] 'process raft request'  (duration: 130.192525ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:09.752437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.091584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-07T19:13:09.752575Z","caller":"traceutil/trace.go:171","msg":"trace[2036305305] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:26159; }","duration":"113.99069ms","start":"2026-09-07T19:13:09.63856Z","end":"2026-09-07T19:13:09.75255Z","steps":["trace[2036305305] 'agreement among raft nodes before linearized reading'  (duration: 112.989811ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:10.176814Z","caller":"traceutil/trace.go:171","msg":"trace[540547045] transaction","detail":"{read_only:false; response_revision:26161; number_of_response:1; }","duration":"102.459252ms","start":"2026-09-07T19:13:10.074327Z","end":"2026-09-07T19:13:10.176786Z","steps":["trace[540547045] 'process raft request'  (duration: 79.433848ms)","trace[540547045] 'compare'  (duration: 22.693784ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:13:10.561836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.504256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/secretgen-controller-cluster-role-binding\" ","response":"range_response_count:1 size:524"}
{"level":"warn","ts":"2026-09-07T19:13:10.561909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.527286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:913"}
{"level":"info","ts":"2026-09-07T19:13:10.561932Z","caller":"traceutil/trace.go:171","msg":"trace[202506334] range","detail":"{range_begin:/registry/clusterrolebindings/secretgen-controller-cluster-role-binding; range_end:; response_count:1; response_revision:26161; }","duration":"269.63465ms","start":"2026-09-07T19:13:10.292272Z","end":"2026-09-07T19:13:10.561906Z","steps":["trace[202506334] 'range keys from in-memory index tree'  (duration: 269.340511ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:10.561963Z","caller":"traceutil/trace.go:171","msg":"trace[681691568] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:26161; }","duration":"199.624418ms","start":"2026-09-07T19:13:10.362321Z","end":"2026-09-07T19:13:10.561945Z","steps":["trace[681691568] 'range keys from in-memory index tree'  (duration: 199.402762ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:10.562228Z","caller":"traceutil/trace.go:171","msg":"trace[693147639] linearizableReadLoop","detail":"{readStateIndex:27016; appliedIndex:27015; }","duration":"153.19391ms","start":"2026-09-07T19:13:10.409007Z","end":"2026-09-07T19:13:10.562201Z","steps":["trace[693147639] 'read index received'  (duration: 140.91912ms)","trace[693147639] 'applied index is now lower than readState.Index'  (duration: 12.27305ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:10.562277Z","caller":"traceutil/trace.go:171","msg":"trace[495611407] transaction","detail":"{read_only:false; response_revision:26162; number_of_response:1; }","duration":"172.0168ms","start":"2026-09-07T19:13:10.390213Z","end":"2026-09-07T19:13:10.56223Z","steps":["trace[495611407] 'process raft request'  (duration: 159.821183ms)","trace[495611407] 'compare'  (duration: 11.553798ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:13:10.562446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.419766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-api-6ccbb5764b-h5js6.18d31e40bdd4b868\" ","response":"range_response_count:1 size:1181"}
{"level":"info","ts":"2026-09-07T19:13:10.562519Z","caller":"traceutil/trace.go:171","msg":"trace[1610255039] range","detail":"{range_begin:/registry/events/openstack/cinder-api-6ccbb5764b-h5js6.18d31e40bdd4b868; range_end:; response_count:1; response_revision:26162; }","duration":"153.537509ms","start":"2026-09-07T19:13:10.408966Z","end":"2026-09-07T19:13:10.562504Z","steps":["trace[1610255039] 'agreement among raft nodes before linearized reading'  (duration: 153.327723ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:13.787542Z","caller":"traceutil/trace.go:171","msg":"trace[1839405606] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"104.691438ms","start":"2026-09-07T19:13:13.68283Z","end":"2026-09-07T19:13:13.787521Z","steps":["trace[1839405606] 'process raft request'  (duration: 34.81094ms)","trace[1839405606] 'compare'  (duration: 69.735884ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:14.851248Z","caller":"traceutil/trace.go:171","msg":"trace[809733162] linearizableReadLoop","detail":"{readStateIndex:27048; appliedIndex:27047; }","duration":"212.929909ms","start":"2026-09-07T19:13:14.638292Z","end":"2026-09-07T19:13:14.851222Z","steps":["trace[809733162] 'read index received'  (duration: 212.732413ms)","trace[809733162] 'applied index is now lower than readState.Index'  (duration: 196.116µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:14.851307Z","caller":"traceutil/trace.go:171","msg":"trace[1870762805] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"215.71699ms","start":"2026-09-07T19:13:14.635559Z","end":"2026-09-07T19:13:14.851276Z","steps":["trace[1870762805] 'process raft request'  (duration: 215.470713ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:14.851437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.130615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:1 size:732"}
{"level":"info","ts":"2026-09-07T19:13:14.85155Z","caller":"traceutil/trace.go:171","msg":"trace[190458496] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:1; response_revision:26193; }","duration":"213.252608ms","start":"2026-09-07T19:13:14.638274Z","end":"2026-09-07T19:13:14.851526Z","steps":["trace[190458496] 'agreement among raft nodes before linearized reading'  (duration: 213.057363ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:14.898114Z","caller":"traceutil/trace.go:171","msg":"trace[2053279910] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"152.819268ms","start":"2026-09-07T19:13:14.745266Z","end":"2026-09-07T19:13:14.898085Z","steps":["trace[2053279910] 'process raft request'  (duration: 152.573691ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:14.898297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.38259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:676"}
{"level":"info","ts":"2026-09-07T19:13:14.898379Z","caller":"traceutil/trace.go:171","msg":"trace[555394763] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:26195; }","duration":"231.517004ms","start":"2026-09-07T19:13:14.666842Z","end":"2026-09-07T19:13:14.898359Z","steps":["trace[555394763] 'agreement among raft nodes before linearized reading'  (duration: 231.317258ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:15.036621Z","caller":"traceutil/trace.go:171","msg":"trace[1080978748] linearizableReadLoop","detail":"{readStateIndex:27051; appliedIndex:27050; }","duration":"138.55262ms","start":"2026-09-07T19:13:14.898049Z","end":"2026-09-07T19:13:15.036602Z","steps":["trace[1080978748] 'read index received'  (duration: 138.332304ms)","trace[1080978748] 'applied index is now lower than readState.Index'  (duration: 219.616µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:15.036945Z","caller":"traceutil/trace.go:171","msg":"trace[1741476358] transaction","detail":"{read_only:false; response_revision:26196; number_of_response:1; }","duration":"169.721383ms","start":"2026-09-07T19:13:14.867211Z","end":"2026-09-07T19:13:15.036933Z","steps":["trace[1741476358] 'process raft request'  (duration: 169.248009ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:15.037007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.290342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:15.037084Z","caller":"traceutil/trace.go:171","msg":"trace[378459128] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26196; }","duration":"166.428856ms","start":"2026-09-07T19:13:14.870635Z","end":"2026-09-07T19:13:15.037064Z","steps":["trace[378459128] 'agreement among raft nodes before linearized reading'  (duration: 166.261611ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:16.003685Z","caller":"traceutil/trace.go:171","msg":"trace[1675493885] transaction","detail":"{read_only:false; response_revision:26203; number_of_response:1; }","duration":"131.982707ms","start":"2026-09-07T19:13:15.871668Z","end":"2026-09-07T19:13:16.00365Z","steps":["trace[1675493885] 'process raft request'  (duration: 96.6136ms)","trace[1675493885] 'compare'  (duration: 35.254954ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:13:16.003728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.09047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T19:13:16.003767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.411455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:16.003794Z","caller":"traceutil/trace.go:171","msg":"trace[1949575754] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26203; }","duration":"162.193252ms","start":"2026-09-07T19:13:15.841584Z","end":"2026-09-07T19:13:16.003777Z","steps":["trace[1949575754] 'agreement among raft nodes before linearized reading'  (duration: 162.050728ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:16.003824Z","caller":"traceutil/trace.go:171","msg":"trace[813925946] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26203; }","duration":"124.480507ms","start":"2026-09-07T19:13:15.879326Z","end":"2026-09-07T19:13:16.003806Z","steps":["trace[813925946] 'agreement among raft nodes before linearized reading'  (duration: 124.374764ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.003825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.753315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:991"}
{"level":"info","ts":"2026-09-07T19:13:16.003894Z","caller":"traceutil/trace.go:171","msg":"trace[1175400187] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:26203; }","duration":"138.841508ms","start":"2026-09-07T19:13:15.865035Z","end":"2026-09-07T19:13:16.003876Z","steps":["trace[1175400187] 'agreement among raft nodes before linearized reading'  (duration: 138.734935ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.003809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.264265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T19:13:16.003971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.259495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-09-07T19:13:16.004019Z","caller":"traceutil/trace.go:171","msg":"trace[2080581934] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26203; }","duration":"161.315967ms","start":"2026-09-07T19:13:15.842685Z","end":"2026-09-07T19:13:16.004001Z","steps":["trace[2080581934] 'agreement among raft nodes before linearized reading'  (duration: 161.176413ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:16.004062Z","caller":"traceutil/trace.go:171","msg":"trace[1058329574] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26203; }","duration":"161.528592ms","start":"2026-09-07T19:13:15.842458Z","end":"2026-09-07T19:13:16.003986Z","steps":["trace[1058329574] 'agreement among raft nodes before linearized reading'  (duration: 161.258145ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.004183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.617425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-09-07T19:13:16.004232Z","caller":"traceutil/trace.go:171","msg":"trace[430718241] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26203; }","duration":"161.695238ms","start":"2026-09-07T19:13:15.842524Z","end":"2026-09-07T19:13:16.004219Z","steps":["trace[430718241] 'agreement among raft nodes before linearized reading'  (duration: 161.504433ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.004327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.696028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:16.004385Z","caller":"traceutil/trace.go:171","msg":"trace[1895854825] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26203; }","duration":"162.784089ms","start":"2026-09-07T19:13:15.841587Z","end":"2026-09-07T19:13:16.004371Z","steps":["trace[1895854825] 'agreement among raft nodes before linearized reading'  (duration: 162.682727ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.003726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.228159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:772"}
{"level":"warn","ts":"2026-09-07T19:13:16.004395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.973355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:16.004462Z","caller":"traceutil/trace.go:171","msg":"trace[1572588050] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:26203; }","duration":"140.005972ms","start":"2026-09-07T19:13:15.864437Z","end":"2026-09-07T19:13:16.004443Z","steps":["trace[1572588050] 'agreement among raft nodes before linearized reading'  (duration: 139.193278ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:16.00451Z","caller":"traceutil/trace.go:171","msg":"trace[1462090387] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26203; }","duration":"162.08326ms","start":"2026-09-07T19:13:15.842376Z","end":"2026-09-07T19:13:16.004459Z","steps":["trace[1462090387] 'agreement among raft nodes before linearized reading'  (duration: 161.921134ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.004592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.980827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:16.004736Z","caller":"traceutil/trace.go:171","msg":"trace[37936479] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26203; }","duration":"162.062979ms","start":"2026-09-07T19:13:15.842579Z","end":"2026-09-07T19:13:16.004642Z","steps":["trace[37936479] 'agreement among raft nodes before linearized reading'  (duration: 161.303316ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.004281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.532142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:16.005108Z","caller":"traceutil/trace.go:171","msg":"trace[901082864] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26203; }","duration":"163.375608ms","start":"2026-09-07T19:13:15.841711Z","end":"2026-09-07T19:13:16.005087Z","steps":["trace[901082864] 'agreement among raft nodes before linearized reading'  (duration: 162.480161ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:16.507525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.150703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:772"}
{"level":"info","ts":"2026-09-07T19:13:16.507613Z","caller":"traceutil/trace.go:171","msg":"trace[597964595] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:26205; }","duration":"101.259517ms","start":"2026-09-07T19:13:16.406329Z","end":"2026-09-07T19:13:16.507588Z","steps":["trace[597964595] 'range keys from in-memory index tree'  (duration: 101.010199ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:17.453226Z","caller":"traceutil/trace.go:171","msg":"trace[1898443021] linearizableReadLoop","detail":"{readStateIndex:27066; appliedIndex:27065; }","duration":"210.146337ms","start":"2026-09-07T19:13:17.243052Z","end":"2026-09-07T19:13:17.453198Z","steps":["trace[1898443021] 'read index received'  (duration: 209.942831ms)","trace[1898443021] 'applied index is now lower than readState.Index'  (duration: 202.556µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:17.453344Z","caller":"traceutil/trace.go:171","msg":"trace[1292785053] transaction","detail":"{read_only:false; response_revision:26210; number_of_response:1; }","duration":"213.501015ms","start":"2026-09-07T19:13:17.239812Z","end":"2026-09-07T19:13:17.453313Z","steps":["trace[1292785053] 'process raft request'  (duration: 213.230488ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:17.45352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.566462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replication-controller\" ","response":"range_response_count:1 size:764"}
{"level":"warn","ts":"2026-09-07T19:13:17.453523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.228921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-db-sync-w28xq\" ","response":"range_response_count:1 size:7265"}
{"level":"info","ts":"2026-09-07T19:13:17.453574Z","caller":"traceutil/trace.go:171","msg":"trace[1649780541] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replication-controller; range_end:; response_count:1; response_revision:26210; }","duration":"106.658895ms","start":"2026-09-07T19:13:17.3469Z","end":"2026-09-07T19:13:17.453558Z","steps":["trace[1649780541] 'agreement among raft nodes before linearized reading'  (duration: 106.543162ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:17.453582Z","caller":"traceutil/trace.go:171","msg":"trace[457743386] range","detail":"{range_begin:/registry/pods/openstack/magnum-db-sync-w28xq; range_end:; response_count:1; response_revision:26210; }","duration":"109.369915ms","start":"2026-09-07T19:13:17.344197Z","end":"2026-09-07T19:13:17.453567Z","steps":["trace[457743386] 'agreement among raft nodes before linearized reading'  (duration: 109.149408ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:17.453605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.539758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:1 size:639"}
{"level":"info","ts":"2026-09-07T19:13:17.453667Z","caller":"traceutil/trace.go:171","msg":"trace[1148164417] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:1; response_revision:26210; }","duration":"210.616342ms","start":"2026-09-07T19:13:17.243036Z","end":"2026-09-07T19:13:17.453653Z","steps":["trace[1148164417] 'agreement among raft nodes before linearized reading'  (duration: 210.289692ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:17.619105Z","caller":"traceutil/trace.go:171","msg":"trace[2093559691] linearizableReadLoop","detail":"{readStateIndex:27067; appliedIndex:27066; }","duration":"126.072584ms","start":"2026-09-07T19:13:17.493005Z","end":"2026-09-07T19:13:17.619078Z","steps":["trace[2093559691] 'read index received'  (duration: 125.820927ms)","trace[2093559691] 'applied index is now lower than readState.Index'  (duration: 250.297µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:17.619247Z","caller":"traceutil/trace.go:171","msg":"trace[1478184189] transaction","detail":"{read_only:false; response_revision:26211; number_of_response:1; }","duration":"154.313081ms","start":"2026-09-07T19:13:17.464884Z","end":"2026-09-07T19:13:17.619197Z","steps":["trace[1478184189] 'process raft request'  (duration: 153.980962ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:17.619327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.31154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-07T19:13:17.619371Z","caller":"traceutil/trace.go:171","msg":"trace[301356323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26211; }","duration":"126.408264ms","start":"2026-09-07T19:13:17.492946Z","end":"2026-09-07T19:13:17.619355Z","steps":["trace[301356323] 'agreement among raft nodes before linearized reading'  (duration: 126.25517ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:17.779297Z","caller":"traceutil/trace.go:171","msg":"trace[2089633878] transaction","detail":"{read_only:false; response_revision:26212; number_of_response:1; }","duration":"251.494049ms","start":"2026-09-07T19:13:17.527776Z","end":"2026-09-07T19:13:17.77927Z","steps":["trace[2089633878] 'process raft request'  (duration: 213.831276ms)","trace[2089633878] 'compare'  (duration: 37.255902ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:17.77945Z","caller":"traceutil/trace.go:171","msg":"trace[1009501278] linearizableReadLoop","detail":"{readStateIndex:27068; appliedIndex:27067; }","duration":"160.255705ms","start":"2026-09-07T19:13:17.619174Z","end":"2026-09-07T19:13:17.779429Z","steps":["trace[1009501278] 'read index received'  (duration: 122.449717ms)","trace[1009501278] 'applied index is now lower than readState.Index'  (duration: 37.804468ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:17.779513Z","caller":"traceutil/trace.go:171","msg":"trace[1169213550] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"155.941439ms","start":"2026-09-07T19:13:17.62352Z","end":"2026-09-07T19:13:17.779462Z","steps":["trace[1169213550] 'process raft request'  (duration: 155.531047ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:17.779539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.970552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:772"}
{"level":"info","ts":"2026-09-07T19:13:17.779604Z","caller":"traceutil/trace.go:171","msg":"trace[159392953] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:26214; }","duration":"195.059595ms","start":"2026-09-07T19:13:17.584524Z","end":"2026-09-07T19:13:17.779583Z","steps":["trace[159392953] 'agreement among raft nodes before linearized reading'  (duration: 194.929401ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:17.779629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.164481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:1 size:639"}
{"level":"info","ts":"2026-09-07T19:13:17.779703Z","caller":"traceutil/trace.go:171","msg":"trace[271208591] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:1; response_revision:26214; }","duration":"205.281854ms","start":"2026-09-07T19:13:17.574405Z","end":"2026-09-07T19:13:17.779687Z","steps":["trace[271208591] 'agreement among raft nodes before linearized reading'  (duration: 205.1453ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:17.930449Z","caller":"traceutil/trace.go:171","msg":"trace[151074321] linearizableReadLoop","detail":"{readStateIndex:27071; appliedIndex:27070; }","duration":"114.529755ms","start":"2026-09-07T19:13:17.815895Z","end":"2026-09-07T19:13:17.930425Z","steps":["trace[151074321] 'read index received'  (duration: 68.297811ms)","trace[151074321] 'applied index is now lower than readState.Index'  (duration: 46.231004ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:17.930551Z","caller":"traceutil/trace.go:171","msg":"trace[1419704601] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"145.856753ms","start":"2026-09-07T19:13:17.784666Z","end":"2026-09-07T19:13:17.930523Z","steps":["trace[1419704601] 'process raft request'  (duration: 99.532616ms)","trace[1419704601] 'compare'  (duration: 46.110671ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:13:17.930599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.690321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:17.930658Z","caller":"traceutil/trace.go:171","msg":"trace[1071822434] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26215; }","duration":"114.795694ms","start":"2026-09-07T19:13:17.815845Z","end":"2026-09-07T19:13:17.930641Z","steps":["trace[1071822434] 'agreement among raft nodes before linearized reading'  (duration: 114.690641ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:20.650845Z","caller":"traceutil/trace.go:171","msg":"trace[1901533646] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"109.752895ms","start":"2026-09-07T19:13:20.541061Z","end":"2026-09-07T19:13:20.650814Z","steps":["trace[1901533646] 'process raft request'  (duration: 109.497437ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:23.259154Z","caller":"traceutil/trace.go:171","msg":"trace[401218910] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27111; }","duration":"191.084849ms","start":"2026-09-07T19:13:23.068046Z","end":"2026-09-07T19:13:23.259131Z","steps":["trace[401218910] 'read index received'  (duration: 190.672048ms)","trace[401218910] 'applied index is now lower than readState.Index'  (duration: 411.431µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:23.259178Z","caller":"traceutil/trace.go:171","msg":"trace[1913678581] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"205.510702ms","start":"2026-09-07T19:13:23.053642Z","end":"2026-09-07T19:13:23.259152Z","steps":["trace[1913678581] 'process raft request'  (duration: 205.113051ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:23.259344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.280905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/capi-kubeadm-bootstrap-system\" ","response":"range_response_count:1 size:616"}
{"level":"info","ts":"2026-09-07T19:13:23.259377Z","caller":"traceutil/trace.go:171","msg":"trace[406820081] range","detail":"{range_begin:/registry/namespaces/capi-kubeadm-bootstrap-system; range_end:; response_count:1; response_revision:26255; }","duration":"191.353637ms","start":"2026-09-07T19:13:23.068015Z","end":"2026-09-07T19:13:23.259368Z","steps":["trace[406820081] 'agreement among raft nodes before linearized reading'  (duration: 191.212203ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:23.465839Z","caller":"traceutil/trace.go:171","msg":"trace[1492413731] linearizableReadLoop","detail":"{readStateIndex:27114; appliedIndex:27112; }","duration":"206.612684ms","start":"2026-09-07T19:13:23.259203Z","end":"2026-09-07T19:13:23.465816Z","steps":["trace[1492413731] 'read index received'  (duration: 206.367757ms)","trace[1492413731] 'applied index is now lower than readState.Index'  (duration: 244.067µs)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:23.466131Z","caller":"traceutil/trace.go:171","msg":"trace[893983360] transaction","detail":"{read_only:false; response_revision:26256; number_of_response:1; }","duration":"273.02745ms","start":"2026-09-07T19:13:23.193092Z","end":"2026-09-07T19:13:23.466119Z","steps":["trace[893983360] 'process raft request'  (duration: 272.493524ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:23.466419Z","caller":"traceutil/trace.go:171","msg":"trace[1199207414] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"225.177568ms","start":"2026-09-07T19:13:23.241232Z","end":"2026-09-07T19:13:23.466409Z","steps":["trace[1199207414] 'process raft request'  (duration: 224.523828ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:23.466676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.182366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/capi-kubeadm-control-plane-system\" ","response":"range_response_count:1 size:632"}
{"level":"info","ts":"2026-09-07T19:13:23.466731Z","caller":"traceutil/trace.go:171","msg":"trace[1015642336] range","detail":"{range_begin:/registry/namespaces/capi-kubeadm-control-plane-system; range_end:; response_count:1; response_revision:26257; }","duration":"384.28723ms","start":"2026-09-07T19:13:23.082433Z","end":"2026-09-07T19:13:23.46672Z","steps":["trace[1015642336] 'agreement among raft nodes before linearized reading'  (duration: 384.145296ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:23.466758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T19:13:23.082411Z","time spent":"384.34035ms","remote":"127.0.0.1:58840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":654,"request content":"key:\"/registry/namespaces/capi-kubeadm-control-plane-system\" "}
{"level":"warn","ts":"2026-09-07T19:13:23.466953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.018379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/capi-kubeadm-bootstrap-system\" ","response":"range_response_count:1 size:616"}
{"level":"info","ts":"2026-09-07T19:13:23.466984Z","caller":"traceutil/trace.go:171","msg":"trace[735321116] range","detail":"{range_begin:/registry/namespaces/capi-kubeadm-bootstrap-system; range_end:; response_count:1; response_revision:26257; }","duration":"202.079051ms","start":"2026-09-07T19:13:23.264897Z","end":"2026-09-07T19:13:23.466976Z","steps":["trace[735321116] 'agreement among raft nodes before linearized reading'  (duration: 201.996479ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:24.097767Z","caller":"traceutil/trace.go:171","msg":"trace[334764298] linearizableReadLoop","detail":"{readStateIndex:27118; appliedIndex:27117; }","duration":"101.002239ms","start":"2026-09-07T19:13:23.996734Z","end":"2026-09-07T19:13:24.097736Z","steps":["trace[334764298] 'read index received'  (duration: 8.774147ms)","trace[334764298] 'applied index is now lower than readState.Index'  (duration: 92.226922ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:24.097806Z","caller":"traceutil/trace.go:171","msg":"trace[661600963] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"108.413038ms","start":"2026-09-07T19:13:23.989366Z","end":"2026-09-07T19:13:24.097779Z","steps":["trace[661600963] 'process raft request'  (duration: 16.115733ms)","trace[661600963] 'compare'  (duration: 92.103649ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:13:24.09808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.324538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"}
{"level":"info","ts":"2026-09-07T19:13:24.098145Z","caller":"traceutil/trace.go:171","msg":"trace[1589856096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26261; }","duration":"101.431342ms","start":"2026-09-07T19:13:23.9967Z","end":"2026-09-07T19:13:24.098131Z","steps":["trace[1589856096] 'agreement among raft nodes before linearized reading'  (duration: 101.196195ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:24.100453Z","caller":"traceutil/trace.go:171","msg":"trace[277945990] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"103.39938ms","start":"2026-09-07T19:13:23.997026Z","end":"2026-09-07T19:13:24.100426Z","steps":["trace[277945990] 'process raft request'  (duration: 103.053179ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:26.081734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.662165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:26.081845Z","caller":"traceutil/trace.go:171","msg":"trace[334112037] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26275; }","duration":"108.826169ms","start":"2026-09-07T19:13:25.972991Z","end":"2026-09-07T19:13:26.081817Z","steps":["trace[334112037] 'range keys from in-memory index tree'  (duration: 108.570792ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:26.082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.989504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:26.082106Z","caller":"traceutil/trace.go:171","msg":"trace[366091253] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26275; }","duration":"109.142938ms","start":"2026-09-07T19:13:25.972944Z","end":"2026-09-07T19:13:26.082087Z","steps":["trace[366091253] 'range keys from in-memory index tree'  (duration: 108.89008ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:26.316187Z","caller":"traceutil/trace.go:171","msg":"trace[1675779823] linearizableReadLoop","detail":"{readStateIndex:27135; appliedIndex:27133; }","duration":"118.827012ms","start":"2026-09-07T19:13:26.197335Z","end":"2026-09-07T19:13:26.316162Z","steps":["trace[1675779823] 'read index received'  (duration: 31.413831ms)","trace[1675779823] 'applied index is now lower than readState.Index'  (duration: 87.411981ms)"],"step_count":2}
{"level":"info","ts":"2026-09-07T19:13:26.316272Z","caller":"traceutil/trace.go:171","msg":"trace[49229946] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"119.000356ms","start":"2026-09-07T19:13:26.197234Z","end":"2026-09-07T19:13:26.316235Z","steps":["trace[49229946] 'process raft request'  (duration: 118.78738ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:26.316351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.991507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:26.31643Z","caller":"traceutil/trace.go:171","msg":"trace[521474052] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:26278; }","duration":"119.099629ms","start":"2026-09-07T19:13:26.197312Z","end":"2026-09-07T19:13:26.316412Z","steps":["trace[521474052] 'agreement among raft nodes before linearized reading'  (duration: 118.966956ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:26.316651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.381479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T19:13:26.316657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.200233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-09-07T19:13:26.31669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.464782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:26.316726Z","caller":"traceutil/trace.go:171","msg":"trace[470418266] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:26278; }","duration":"119.295696ms","start":"2026-09-07T19:13:26.197414Z","end":"2026-09-07T19:13:26.316709Z","steps":["trace[470418266] 'agreement among raft nodes before linearized reading'  (duration: 119.166762ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:26.316768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.149161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"warn","ts":"2026-09-07T19:13:26.31677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.239014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:26.3168Z","caller":"traceutil/trace.go:171","msg":"trace[969284008] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26278; }","duration":"119.186922ms","start":"2026-09-07T19:13:26.197605Z","end":"2026-09-07T19:13:26.316792Z","steps":["trace[969284008] 'agreement among raft nodes before linearized reading'  (duration: 119.087319ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:26.316732Z","caller":"traceutil/trace.go:171","msg":"trace[536173490] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26278; }","duration":"118.567224ms","start":"2026-09-07T19:13:26.198152Z","end":"2026-09-07T19:13:26.316719Z","steps":["trace[536173490] 'agreement among raft nodes before linearized reading'  (duration: 118.448641ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:26.316837Z","caller":"traceutil/trace.go:171","msg":"trace[1639056314] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:26278; }","duration":"119.370458ms","start":"2026-09-07T19:13:26.19745Z","end":"2026-09-07T19:13:26.316821Z","steps":["trace[1639056314] 'agreement among raft nodes before linearized reading'  (duration: 119.263055ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:26.31673Z","caller":"traceutil/trace.go:171","msg":"trace[1720604275] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26278; }","duration":"117.476463ms","start":"2026-09-07T19:13:26.199224Z","end":"2026-09-07T19:13:26.3167Z","steps":["trace[1720604275] 'agreement among raft nodes before linearized reading'  (duration: 117.37186ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:26.316661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.984148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:26.316987Z","caller":"traceutil/trace.go:171","msg":"trace[1202183066] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:26278; }","duration":"118.332747ms","start":"2026-09-07T19:13:26.198641Z","end":"2026-09-07T19:13:26.316973Z","steps":["trace[1202183066] 'agreement among raft nodes before linearized reading'  (duration: 117.974647ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:26.317403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.639298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"}
{"level":"info","ts":"2026-09-07T19:13:26.317479Z","caller":"traceutil/trace.go:171","msg":"trace[1758770974] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:26278; }","duration":"116.777361ms","start":"2026-09-07T19:13:26.200678Z","end":"2026-09-07T19:13:26.317455Z","steps":["trace[1758770974] 'agreement among raft nodes before linearized reading'  (duration: 116.130103ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:13:33.538404Z","caller":"traceutil/trace.go:171","msg":"trace[1616494656] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"108.863999ms","start":"2026-09-07T19:13:33.429341Z","end":"2026-09-07T19:13:33.538205Z","steps":["trace[1616494656] 'process raft request'  (duration: 85.177925ms)","trace[1616494656] 'compare'  (duration: 23.56311ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-07T19:13:38.264507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.787348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:705"}
{"level":"info","ts":"2026-09-07T19:13:38.264561Z","caller":"traceutil/trace.go:171","msg":"trace[707246580] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:26362; }","duration":"108.891601ms","start":"2026-09-07T19:13:38.155656Z","end":"2026-09-07T19:13:38.264547Z","steps":["trace[707246580] 'range keys from in-memory index tree'  (duration: 108.674024ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-07T19:13:38.264519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.801124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-07T19:13:38.264864Z","caller":"traceutil/trace.go:171","msg":"trace[1244473768] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26362; }","duration":"128.225106ms","start":"2026-09-07T19:13:38.136617Z","end":"2026-09-07T19:13:38.264842Z","steps":["trace[1244473768] 'count revisions from in-memory index tree'  (duration: 127.723402ms)"],"step_count":1}
{"level":"info","ts":"2026-09-07T19:16:37.220558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25485}
{"level":"info","ts":"2026-09-07T19:16:37.369797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25485,"took":"144.743059ms","hash":1422842774,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-07T19:16:37.369868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1422842774,"revision":25485,"compact-revision":22754}
{"level":"info","ts":"2026-09-07T19:16:38.352001Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006fc3.wal"}