{"level":"warn","ts":"2026-10-16T00:11:00.569163Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-10-16T00:11:00.569271Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-10-16T00:11:00.569345Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-10-16T00:11:00.569354Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]}
{"level":"info","ts":"2026-10-16T00:11:00.56938Z","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-10-16T00:11:00.570449Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]}
{"level":"info","ts":"2026-10-16T00:11:00.570547Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-10-16T00:11:00.574911Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.120093ms"}
{"level":"info","ts":"2026-10-16T00:11:00.581711Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"}
{"level":"info","ts":"2026-10-16T00:11:00.581792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"}
{"level":"info","ts":"2026-10-16T00:11:00.581812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"}
{"level":"info","ts":"2026-10-16T00:11:00.581823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-10-16T00:11:00.581838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"}
{"level":"info","ts":"2026-10-16T00:11:00.581905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"}
{"level":"warn","ts":"2026-10-16T00:11:00.586586Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-10-16T00:11:00.588623Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-10-16T00:11:00.589981Z","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-10-16T00:11:00.593132Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-10-16T00:11:00.593299Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-10-16T00:11:00.593553Z","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-10-16T00:11:00.593628Z","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-10-16T00:11:00.593644Z","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-10-16T00:11:00.59452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"}
{"level":"info","ts":"2026-10-16T00:11:00.594676Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]}
{"level":"info","ts":"2026-10-16T00:11:00.598735Z","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-10-16T00:11:00.598896Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"}
{"level":"info","ts":"2026-10-16T00:11:00.598953Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"}
{"level":"info","ts":"2026-10-16T00:11:00.599109Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-10-16T00:11:00.599147Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-10-16T00:11:00.782573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"}
{"level":"info","ts":"2026-10-16T00:11:00.782695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"}
{"level":"info","ts":"2026-10-16T00:11:00.782753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"}
{"level":"info","ts":"2026-10-16T00:11:00.782808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"}
{"level":"info","ts":"2026-10-16T00:11:00.782865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"}
{"level":"info","ts":"2026-10-16T00:11:00.782916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"}
{"level":"info","ts":"2026-10-16T00:11:00.782951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"}
{"level":"info","ts":"2026-10-16T00:11:00.785877Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-16T00:11:00.787205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-16T00:11:00.787201Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"}
{"level":"info","ts":"2026-10-16T00:11:00.787253Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-10-16T00:11:00.78752Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-10-16T00:11:00.787576Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-10-16T00:11:00.787681Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-16T00:11:00.787809Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-16T00:11:00.787896Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-10-16T00:11:00.789768Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-10-16T00:11:00.790632Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"}
{"level":"info","ts":"2026-10-16T00:11:53.310575Z","caller":"traceutil/trace.go:171","msg":"trace[1605703736] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"293.92068ms","start":"2026-10-16T00:11:53.01663Z","end":"2026-10-16T00:11:53.31055Z","steps":["trace[1605703736] 'read index received'  (duration: 293.07826ms)","trace[1605703736] 'applied index is now lower than readState.Index'  (duration: 841.53µs)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:11:53.31086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.221406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m4jvq\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-10-16T00:11:53.310975Z","caller":"traceutil/trace.go:171","msg":"trace[629356792] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m4jvq; range_end:; response_count:1; response_revision:606; }","duration":"294.38987ms","start":"2026-10-16T00:11:53.016567Z","end":"2026-10-16T00:11:53.310957Z","steps":["trace[629356792] 'agreement among raft nodes before linearized reading'  (duration: 294.143855ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:11:53.310867Z","caller":"traceutil/trace.go:171","msg":"trace[1224087619] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"301.550827ms","start":"2026-10-16T00:11:53.009074Z","end":"2026-10-16T00:11:53.310625Z","steps":["trace[1224087619] 'process raft request'  (duration: 300.888971ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:11:53.31252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:53.009031Z","time spent":"302.13956ms","remote":"127.0.0.1:33342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" mod_revision:600 > success:<request_put:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" value_size:1036173 >> failure:<request_range:<key:\"/registry/secrets/envoy-gateway-system/sh.helm.release.v1.envoy-gateway.v1\" > >"}
{"level":"info","ts":"2026-10-16T00:11:53.432977Z","caller":"traceutil/trace.go:171","msg":"trace[1074500667] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"100.638935ms","start":"2026-10-16T00:11:53.332304Z","end":"2026-10-16T00:11:53.432943Z","steps":["trace[1074500667] 'process raft request'  (duration: 74.431307ms)","trace[1074500667] 'compare'  (duration: 26.057795ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:11:53.458028Z","caller":"traceutil/trace.go:171","msg":"trace[1561508623] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"123.116096ms","start":"2026-10-16T00:11:53.33486Z","end":"2026-10-16T00:11:53.457976Z","steps":["trace[1561508623] 'process raft request'  (duration: 122.807009ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:11:53.885893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.996866ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:11:53.886144Z","caller":"traceutil/trace.go:171","msg":"trace[638689495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:611; }","duration":"152.311103ms","start":"2026-10-16T00:11:53.73381Z","end":"2026-10-16T00:11:53.886121Z","steps":["trace[638689495] 'range keys from in-memory index tree'  (duration: 151.972726ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:11:53.88615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.59727ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409309986 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:595 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-16T00:11:53.886573Z","caller":"traceutil/trace.go:171","msg":"trace[326172418] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"415.233524ms","start":"2026-10-16T00:11:53.471325Z","end":"2026-10-16T00:11:53.886558Z","steps":["trace[326172418] 'read index received'  (duration: 6.661694ms)","trace[326172418] 'applied index is now lower than readState.Index'  (duration: 408.57038ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:11:53.886757Z","caller":"traceutil/trace.go:171","msg":"trace[228977650] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"421.96646ms","start":"2026-10-16T00:11:53.464725Z","end":"2026-10-16T00:11:53.886692Z","steps":["trace[228977650] 'process raft request'  (duration: 290.584662ms)","trace[228977650] 'compare'  (duration: 130.430417ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:11:53.8868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.459009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-10-16T00:11:53.88707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.281912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-10-16T00:11:53.88704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:53.464705Z","time spent":"422.263387ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:595 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-10-16T00:11:53.887114Z","caller":"traceutil/trace.go:171","msg":"trace[1399568583] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:612; }","duration":"415.823598ms","start":"2026-10-16T00:11:53.471255Z","end":"2026-10-16T00:11:53.887078Z","steps":["trace[1399568583] 'agreement among raft nodes before linearized reading'  (duration: 415.461159ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:11:53.887344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:53.47124Z","time spent":"416.090395ms","remote":"127.0.0.1:50530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "}
{"level":"info","ts":"2026-10-16T00:11:53.88741Z","caller":"traceutil/trace.go:171","msg":"trace[845283154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"380.507779ms","start":"2026-10-16T00:11:53.506721Z","end":"2026-10-16T00:11:53.887229Z","steps":["trace[845283154] 'agreement among raft nodes before linearized reading'  (duration: 380.1808ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:11:53.887604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:11:53.506701Z","time spent":"380.798056ms","remote":"127.0.0.1:33262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-10-16T00:14:09.366126Z","caller":"traceutil/trace.go:171","msg":"trace[1116986642] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"136.923032ms","start":"2026-10-16T00:14:09.229166Z","end":"2026-10-16T00:14:09.366089Z","steps":["trace[1116986642] 'process raft request'  (duration: 136.633376ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:14:09.653826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.522939ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409313217 > lease_revoke:<id:56f7a1420c3c76af>","response":"size:28"}
{"level":"info","ts":"2026-10-16T00:14:09.653959Z","caller":"traceutil/trace.go:171","msg":"trace[577938973] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2247; }","duration":"287.782806ms","start":"2026-10-16T00:14:09.366157Z","end":"2026-10-16T00:14:09.65394Z","steps":["trace[577938973] 'read index received'  (duration: 140.150044ms)","trace[577938973] 'applied index is now lower than readState.Index'  (duration: 147.631222ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:14:09.65408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.904079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:14:09.654124Z","caller":"traceutil/trace.go:171","msg":"trace[724748509] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2170; }","duration":"287.98567ms","start":"2026-10-16T00:14:09.366128Z","end":"2026-10-16T00:14:09.654114Z","steps":["trace[724748509] 'agreement among raft nodes before linearized reading'  (duration: 287.896228ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:14:09.662906Z","caller":"traceutil/trace.go:171","msg":"trace[2088660848] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"281.965236ms","start":"2026-10-16T00:14:09.380908Z","end":"2026-10-16T00:14:09.662873Z","steps":["trace[2088660848] 'process raft request'  (duration: 281.804133ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:14:09.662987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.548075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"}
{"level":"info","ts":"2026-10-16T00:14:09.663052Z","caller":"traceutil/trace.go:171","msg":"trace[1151856260] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2171; }","duration":"293.649527ms","start":"2026-10-16T00:14:09.369383Z","end":"2026-10-16T00:14:09.663032Z","steps":["trace[1151856260] 'agreement among raft nodes before linearized reading'  (duration: 293.467433ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:14:09.663169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.766056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-10-16T00:14:09.663214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.898599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"}
{"level":"info","ts":"2026-10-16T00:14:09.663331Z","caller":"traceutil/trace.go:171","msg":"trace[1768510611] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2171; }","duration":"136.079493ms","start":"2026-10-16T00:14:09.527226Z","end":"2026-10-16T00:14:09.663305Z","steps":["trace[1768510611] 'agreement among raft nodes before linearized reading'  (duration: 135.722635ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:14:09.663362Z","caller":"traceutil/trace.go:171","msg":"trace[228088689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2171; }","duration":"156.94568ms","start":"2026-10-16T00:14:09.506335Z","end":"2026-10-16T00:14:09.663281Z","steps":["trace[228088689] 'agreement among raft nodes before linearized reading'  (duration: 156.757406ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:14:09.803785Z","caller":"traceutil/trace.go:171","msg":"trace[627782691] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"133.055065ms","start":"2026-10-16T00:14:09.670705Z","end":"2026-10-16T00:14:09.80376Z","steps":["trace[627782691] 'process raft request'  (duration: 50.930099ms)","trace[627782691] 'compare'  (duration: 81.942792ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:14:09.803961Z","caller":"traceutil/trace.go:171","msg":"trace[1662916234] linearizableReadLoop","detail":"{readStateIndex:2251; appliedIndex:2249; }","duration":"131.479419ms","start":"2026-10-16T00:14:09.672462Z","end":"2026-10-16T00:14:09.803941Z","steps":["trace[1662916234] 'read index received'  (duration: 49.162489ms)","trace[1662916234] 'applied index is now lower than readState.Index'  (duration: 82.31568ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:14:09.804126Z","caller":"traceutil/trace.go:171","msg":"trace[1505732196] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"131.943482ms","start":"2026-10-16T00:14:09.672153Z","end":"2026-10-16T00:14:09.804096Z","steps":["trace[1505732196] 'process raft request'  (duration: 131.563413ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:14:09.804412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.92635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18deda3692fb1f04\" ","response":"range_response_count:1 size:846"}
{"level":"info","ts":"2026-10-16T00:14:09.804466Z","caller":"traceutil/trace.go:171","msg":"trace[544449247] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18deda3692fb1f04; range_end:; response_count:1; response_revision:2173; }","duration":"132.010002ms","start":"2026-10-16T00:14:09.672441Z","end":"2026-10-16T00:14:09.804451Z","steps":["trace[544449247] 'agreement among raft nodes before linearized reading'  (duration: 131.671004ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:17:36.919187Z","caller":"traceutil/trace.go:171","msg":"trace[1642437242] linearizableReadLoop","detail":"{readStateIndex:4468; appliedIndex:4466; }","duration":"116.066628ms","start":"2026-10-16T00:17:36.803086Z","end":"2026-10-16T00:17:36.919153Z","steps":["trace[1642437242] 'read index received'  (duration: 63.757762ms)","trace[1642437242] 'applied index is now lower than readState.Index'  (duration: 52.307286ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:17:36.919415Z","caller":"traceutil/trace.go:171","msg":"trace[52643136] transaction","detail":"{read_only:false; response_revision:4280; number_of_response:1; }","duration":"111.02649ms","start":"2026-10-16T00:17:36.808231Z","end":"2026-10-16T00:17:36.919257Z","steps":["trace[52643136] 'process raft request'  (duration: 110.852766ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:17:36.919497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.388844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:17:36.920247Z","caller":"traceutil/trace.go:171","msg":"trace[1493341954] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4280; }","duration":"117.263233ms","start":"2026-10-16T00:17:36.80292Z","end":"2026-10-16T00:17:36.920183Z","steps":["trace[1493341954] 'agreement among raft nodes before linearized reading'  (duration: 116.437746ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:17:36.91941Z","caller":"traceutil/trace.go:171","msg":"trace[1621748230] transaction","detail":"{read_only:false; response_revision:4279; number_of_response:1; }","duration":"116.520719ms","start":"2026-10-16T00:17:36.802822Z","end":"2026-10-16T00:17:36.919343Z","steps":["trace[1621748230] 'process raft request'  (duration: 116.069939ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:17:43.161779Z","caller":"traceutil/trace.go:171","msg":"trace[2017006997] transaction","detail":"{read_only:false; response_revision:4440; number_of_response:1; }","duration":"114.77004ms","start":"2026-10-16T00:17:43.046966Z","end":"2026-10-16T00:17:43.161736Z","steps":["trace[2017006997] 'process raft request'  (duration: 102.857814ms)","trace[2017006997] 'compare'  (duration: 11.362114ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:17:44.96307Z","caller":"traceutil/trace.go:171","msg":"trace[1525561570] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"115.545136ms","start":"2026-10-16T00:17:44.847488Z","end":"2026-10-16T00:17:44.963033Z","steps":["trace[1525561570] 'process raft request'  (duration: 115.356142ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:17:44.972705Z","caller":"traceutil/trace.go:171","msg":"trace[500351900] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"117.230303ms","start":"2026-10-16T00:17:44.855447Z","end":"2026-10-16T00:17:44.972677Z","steps":["trace[500351900] 'process raft request'  (duration: 116.956388ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:17:47.089261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.599264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4b01f3d4-7ce0-414f-b975-365ab4474d55\" ","response":"range_response_count:1 size:3904"}
{"level":"info","ts":"2026-10-16T00:17:47.08936Z","caller":"traceutil/trace.go:171","msg":"trace[1212336162] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4b01f3d4-7ce0-414f-b975-365ab4474d55; range_end:; response_count:1; response_revision:4557; }","duration":"106.755287ms","start":"2026-10-16T00:17:46.982581Z","end":"2026-10-16T00:17:47.089337Z","steps":["trace[1212336162] 'range keys from in-memory index tree'  (duration: 106.420781ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:18:03.11805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.776394ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409319857 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:4787 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-16T00:18:03.118242Z","caller":"traceutil/trace.go:171","msg":"trace[1867143584] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5046; }","duration":"238.904894ms","start":"2026-10-16T00:18:02.879318Z","end":"2026-10-16T00:18:03.118223Z","steps":["trace[1867143584] 'read index received'  (duration: 107.854904ms)","trace[1867143584] 'applied index is now lower than readState.Index'  (duration: 131.04897ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:18:03.118511Z","caller":"traceutil/trace.go:171","msg":"trace[293402200] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"452.241123ms","start":"2026-10-16T00:18:02.666239Z","end":"2026-10-16T00:18:03.11848Z","steps":["trace[293402200] 'process raft request'  (duration: 451.918956ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:18:03.118662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:18:02.666221Z","time spent":"452.351556ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:4788 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:461 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-10-16T00:18:03.118704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.359774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8sqnc\" ","response":"range_response_count:1 size:6524"}
{"level":"info","ts":"2026-10-16T00:18:03.118854Z","caller":"traceutil/trace.go:171","msg":"trace[817212476] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8sqnc; range_end:; response_count:1; response_revision:4835; }","duration":"239.511668ms","start":"2026-10-16T00:18:02.879302Z","end":"2026-10-16T00:18:03.118813Z","steps":["trace[817212476] 'agreement among raft nodes before linearized reading'  (duration: 239.15886ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:18:03.11917Z","caller":"traceutil/trace.go:171","msg":"trace[418708315] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"452.410247ms","start":"2026-10-16T00:18:02.666166Z","end":"2026-10-16T00:18:03.118577Z","steps":["trace[418708315] 'process raft request'  (duration: 321.004969ms)","trace[418708315] 'compare'  (duration: 130.682272ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:18:03.119515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:18:02.666152Z","time spent":"453.129743ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:4787 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-10-16T00:18:11.299645Z","caller":"traceutil/trace.go:171","msg":"trace[1070319229] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5236; }","duration":"121.593965ms","start":"2026-10-16T00:18:11.178025Z","end":"2026-10-16T00:18:11.299619Z","steps":["trace[1070319229] 'read index received'  (duration: 80.397179ms)","trace[1070319229] 'applied index is now lower than readState.Index'  (duration: 41.195546ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:18:11.299791Z","caller":"traceutil/trace.go:171","msg":"trace[1737014364] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"129.69537ms","start":"2026-10-16T00:18:11.170051Z","end":"2026-10-16T00:18:11.299746Z","steps":["trace[1737014364] 'process raft request'  (duration: 88.451743ms)","trace[1737014364] 'compare'  (duration: 40.995012ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:18:11.299942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.897991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-10-16T00:18:11.300011Z","caller":"traceutil/trace.go:171","msg":"trace[2036615658] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5018; }","duration":"122.010914ms","start":"2026-10-16T00:18:11.177983Z","end":"2026-10-16T00:18:11.299994Z","steps":["trace[2036615658] 'agreement among raft nodes before linearized reading'  (duration: 121.782259ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:18:11.494038Z","caller":"traceutil/trace.go:171","msg":"trace[1138604573] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"190.051892ms","start":"2026-10-16T00:18:11.303952Z","end":"2026-10-16T00:18:11.494004Z","steps":["trace[1138604573] 'process raft request'  (duration: 126.559151ms)","trace[1138604573] 'compare'  (duration: 63.237956ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:18:11.494313Z","caller":"traceutil/trace.go:171","msg":"trace[1566360251] linearizableReadLoop","detail":"{readStateIndex:5240; appliedIndex:5237; }","duration":"186.883754ms","start":"2026-10-16T00:18:11.307402Z","end":"2026-10-16T00:18:11.494286Z","steps":["trace[1566360251] 'read index received'  (duration: 123.080737ms)","trace[1566360251] 'applied index is now lower than readState.Index'  (duration: 63.801897ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:18:11.494368Z","caller":"traceutil/trace.go:171","msg":"trace[2025966197] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"188.297365ms","start":"2026-10-16T00:18:11.306056Z","end":"2026-10-16T00:18:11.494353Z","steps":["trace[2025966197] 'process raft request'  (duration: 187.885926ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:18:11.49436Z","caller":"traceutil/trace.go:171","msg":"trace[1331432862] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"187.842644ms","start":"2026-10-16T00:18:11.306495Z","end":"2026-10-16T00:18:11.494337Z","steps":["trace[1331432862] 'process raft request'  (duration: 187.688751ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:18:11.49459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.201151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-10-16T00:18:11.494643Z","caller":"traceutil/trace.go:171","msg":"trace[162174274] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5021; }","duration":"187.297923ms","start":"2026-10-16T00:18:11.307327Z","end":"2026-10-16T00:18:11.494624Z","steps":["trace[162174274] 'agreement among raft nodes before linearized reading'  (duration: 187.111449ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:21:00.938057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3142}
{"level":"info","ts":"2026-10-16T00:21:01.042653Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3142,"took":"102.280673ms","hash":4216714149,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":33611776,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-10-16T00:21:01.042733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4216714149,"revision":3142,"compact-revision":-1}
{"level":"info","ts":"2026-10-16T00:24:16.216388Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021e4.wal"}
{"level":"info","ts":"2026-10-16T00:24:32.538626Z","caller":"traceutil/trace.go:171","msg":"trace[1735174348] linearizableReadLoop","detail":"{readStateIndex:8792; appliedIndex:8791; }","duration":"101.416754ms","start":"2026-10-16T00:24:32.437184Z","end":"2026-10-16T00:24:32.5386Z","steps":["trace[1735174348] 'read index received'  (duration: 101.22905ms)","trace[1735174348] 'applied index is now lower than readState.Index'  (duration: 186.624µs)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:24:32.538944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.742511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:24:32.538885Z","caller":"traceutil/trace.go:171","msg":"trace[873671215] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"106.137128ms","start":"2026-10-16T00:24:32.432714Z","end":"2026-10-16T00:24:32.538851Z","steps":["trace[873671215] 'process raft request'  (duration: 105.75284ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:24:32.538992Z","caller":"traceutil/trace.go:171","msg":"trace[1665371069] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8406; }","duration":"101.860044ms","start":"2026-10-16T00:24:32.437121Z","end":"2026-10-16T00:24:32.538981Z","steps":["trace[1665371069] 'agreement among raft nodes before linearized reading'  (duration: 101.732611ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:24:33.064472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.436448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:24:33.064552Z","caller":"traceutil/trace.go:171","msg":"trace[1136921206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8410; }","duration":"226.574851ms","start":"2026-10-16T00:24:32.837958Z","end":"2026-10-16T00:24:33.064533Z","steps":["trace[1136921206] 'count revisions from in-memory index tree'  (duration: 226.316126ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:24:33.178546Z","caller":"traceutil/trace.go:171","msg":"trace[1662743159] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"207.50056ms","start":"2026-10-16T00:24:32.97102Z","end":"2026-10-16T00:24:33.178521Z","steps":["trace[1662743159] 'process raft request'  (duration: 207.347637ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:24:34.043834Z","caller":"traceutil/trace.go:171","msg":"trace[1923049442] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8416; }","duration":"135.841072ms","start":"2026-10-16T00:24:33.907761Z","end":"2026-10-16T00:24:34.043602Z","steps":["trace[1923049442] 'process raft request'  (duration: 106.097556ms)","trace[1923049442] 'compare'  (duration: 29.539062ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:24:34.043858Z","caller":"traceutil/trace.go:171","msg":"trace[501278261] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"135.609367ms","start":"2026-10-16T00:24:33.90822Z","end":"2026-10-16T00:24:34.043829Z","steps":["trace[501278261] 'process raft request'  (duration: 135.352261ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:24:34.043926Z","caller":"traceutil/trace.go:171","msg":"trace[1693976745] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"135.745949ms","start":"2026-10-16T00:24:33.908151Z","end":"2026-10-16T00:24:34.043897Z","steps":["trace[1693976745] 'process raft request'  (duration: 135.30938ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:24:36.165608Z","caller":"traceutil/trace.go:171","msg":"trace[1085893680] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"113.166603ms","start":"2026-10-16T00:24:36.052401Z","end":"2026-10-16T00:24:36.165568Z","steps":["trace[1085893680] 'process raft request'  (duration: 17.400763ms)","trace[1085893680] 'compare'  (duration: 95.526445ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:24:36.165644Z","caller":"traceutil/trace.go:171","msg":"trace[1324493816] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"111.529337ms","start":"2026-10-16T00:24:36.054092Z","end":"2026-10-16T00:24:36.165621Z","steps":["trace[1324493816] 'process raft request'  (duration: 111.387943ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:17.176048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.478163ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409330810 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/pods/openstack/rook-ceph-detect-version-7c7g6\" mod_revision:8794 > success:<request_put:<key:\"/registry/pods/openstack/rook-ceph-detect-version-7c7g6\" value_size:4575 >> failure:<request_range:<key:\"/registry/pods/openstack/rook-ceph-detect-version-7c7g6\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-16T00:25:17.176243Z","caller":"traceutil/trace.go:171","msg":"trace[229932094] linearizableReadLoop","detail":"{readStateIndex:9206; appliedIndex:9205; }","duration":"240.400125ms","start":"2026-10-16T00:25:16.935819Z","end":"2026-10-16T00:25:17.176219Z","steps":["trace[229932094] 'read index received'  (duration: 26.538445ms)","trace[229932094] 'applied index is now lower than readState.Index'  (duration: 213.85953ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:25:17.176311Z","caller":"traceutil/trace.go:171","msg":"trace[1568334401] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"243.158787ms","start":"2026-10-16T00:25:16.933119Z","end":"2026-10-16T00:25:17.176278Z","steps":["trace[1568334401] 'process raft request'  (duration: 29.318096ms)","trace[1568334401] 'compare'  (duration: 213.33504ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:25:17.176438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.60709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-7c7g6\" ","response":"range_response_count:1 size:2806"}
{"level":"info","ts":"2026-10-16T00:25:17.176492Z","caller":"traceutil/trace.go:171","msg":"trace[1056807083] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-7c7g6; range_end:; response_count:1; response_revision:8796; }","duration":"240.660851ms","start":"2026-10-16T00:25:16.935814Z","end":"2026-10-16T00:25:17.176475Z","steps":["trace[1056807083] 'agreement among raft nodes before linearized reading'  (duration: 240.468747ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:17.256722Z","caller":"traceutil/trace.go:171","msg":"trace[149411734] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8796; }","duration":"317.152906ms","start":"2026-10-16T00:25:16.939536Z","end":"2026-10-16T00:25:17.256689Z","steps":["trace[149411734] 'process raft request'  (duration: 317.008563ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:17.256934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:16.939509Z","time spent":"317.291458ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4687,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/rook-ceph-detect-version-7c7g6\" mod_revision:8794 > success:<request_put:<key:\"/registry/pods/openstack/rook-ceph-detect-version-7c7g6\" value_size:4639 >> failure:<request_range:<key:\"/registry/pods/openstack/rook-ceph-detect-version-7c7g6\" > >"}
{"level":"info","ts":"2026-10-16T00:25:17.479044Z","caller":"traceutil/trace.go:171","msg":"trace[1103438617] linearizableReadLoop","detail":"{readStateIndex:9208; appliedIndex:9206; }","duration":"227.348947ms","start":"2026-10-16T00:25:17.25167Z","end":"2026-10-16T00:25:17.479019Z","steps":["trace[1103438617] 'read index received'  (duration: 4.874237ms)","trace[1103438617] 'applied index is now lower than readState.Index'  (duration: 222.46969ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:25:17.479111Z","caller":"traceutil/trace.go:171","msg":"trace[36423340] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8797; }","duration":"298.875694ms","start":"2026-10-16T00:25:17.180159Z","end":"2026-10-16T00:25:17.479035Z","steps":["trace[36423340] 'process raft request'  (duration: 201.99809ms)","trace[36423340] 'compare'  (duration: 96.617968ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:25:17.479653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.433464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-10-16T00:25:17.479759Z","caller":"traceutil/trace.go:171","msg":"trace[1290183136] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8798; }","duration":"224.597088ms","start":"2026-10-16T00:25:17.255134Z","end":"2026-10-16T00:25:17.479731Z","steps":["trace[1290183136] 'agreement among raft nodes before linearized reading'  (duration: 224.324812ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:17.479792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.515361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7c7g6\" ","response":"range_response_count:1 size:4653"}
{"level":"info","ts":"2026-10-16T00:25:17.47989Z","caller":"traceutil/trace.go:171","msg":"trace[391674907] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7c7g6; range_end:; response_count:1; response_revision:8798; }","duration":"202.669955ms","start":"2026-10-16T00:25:17.277188Z","end":"2026-10-16T00:25:17.479858Z","steps":["trace[391674907] 'agreement among raft nodes before linearized reading'  (duration: 202.384598ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:17.479797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.107544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-10-16T00:25:17.480267Z","caller":"traceutil/trace.go:171","msg":"trace[2127777226] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8798; }","duration":"228.640807ms","start":"2026-10-16T00:25:17.251599Z","end":"2026-10-16T00:25:17.48024Z","steps":["trace[2127777226] 'agreement among raft nodes before linearized reading'  (duration: 227.622744ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:17.595505Z","caller":"traceutil/trace.go:171","msg":"trace[869531585] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"104.886351ms","start":"2026-10-16T00:25:17.490569Z","end":"2026-10-16T00:25:17.595455Z","steps":["trace[869531585] 'process raft request'  (duration: 104.709257ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:17.595571Z","caller":"traceutil/trace.go:171","msg":"trace[871530788] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"104.986013ms","start":"2026-10-16T00:25:17.490552Z","end":"2026-10-16T00:25:17.595538Z","steps":["trace[871530788] 'process raft request'  (duration: 90.28092ms)","trace[871530788] 'compare'  (duration: 14.121221ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:25:18.619918Z","caller":"traceutil/trace.go:171","msg":"trace[2083103870] linearizableReadLoop","detail":"{readStateIndex:9218; appliedIndex:9217; }","duration":"406.884403ms","start":"2026-10-16T00:25:18.212999Z","end":"2026-10-16T00:25:18.619883Z","steps":["trace[2083103870] 'read index received'  (duration: 406.025294ms)","trace[2083103870] 'applied index is now lower than readState.Index'  (duration: 853.149µs)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:25:18.619966Z","caller":"traceutil/trace.go:171","msg":"trace[212404312] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"463.303765ms","start":"2026-10-16T00:25:18.156588Z","end":"2026-10-16T00:25:18.619892Z","steps":["trace[212404312] 'process raft request'  (duration: 461.867074ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.620229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.202641ms","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-10-16T00:25:18.620272Z","caller":"traceutil/trace.go:171","msg":"trace[664000157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8807; }","duration":"407.334443ms","start":"2026-10-16T00:25:18.212924Z","end":"2026-10-16T00:25:18.620258Z","steps":["trace[664000157] 'agreement among raft nodes before linearized reading'  (duration: 407.118907ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.62026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:18.156559Z","time spent":"463.531781ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:8780 > 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-10-16T00:25:18.620311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:18.212897Z","time spent":"407.404755ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-10-16T00:25:18.62055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.84428ms","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-10-16T00:25:18.620625Z","caller":"traceutil/trace.go:171","msg":"trace[2069435278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8807; }","duration":"134.983955ms","start":"2026-10-16T00:25:18.485621Z","end":"2026-10-16T00:25:18.620605Z","steps":["trace[2069435278] 'agreement among raft nodes before linearized reading'  (duration: 134.693698ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.620667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.787231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-10-16T00:25:18.620954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.888545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-10-16T00:25:18.620992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.796199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:25:18.621022Z","caller":"traceutil/trace.go:171","msg":"trace[382046940] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:8807; }","duration":"146.175399ms","start":"2026-10-16T00:25:18.474817Z","end":"2026-10-16T00:25:18.620992Z","steps":["trace[382046940] 'agreement among raft nodes before linearized reading'  (duration: 145.782471ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:18.621035Z","caller":"traceutil/trace.go:171","msg":"trace[267838694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8807; }","duration":"114.882651ms","start":"2026-10-16T00:25:18.506141Z","end":"2026-10-16T00:25:18.621024Z","steps":["trace[267838694] 'agreement among raft nodes before linearized reading'  (duration: 114.822549ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.62057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.630681ms","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":"warn","ts":"2026-10-16T00:25:18.621169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.409925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:2953"}
{"level":"info","ts":"2026-10-16T00:25:18.621219Z","caller":"traceutil/trace.go:171","msg":"trace[1662842290] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8807; }","duration":"402.470596ms","start":"2026-10-16T00:25:18.218735Z","end":"2026-10-16T00:25:18.621205Z","steps":["trace[1662842290] 'agreement among raft nodes before linearized reading'  (duration: 402.351783ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:18.621039Z","caller":"traceutil/trace.go:171","msg":"trace[1007407330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8807; }","duration":"403.037729ms","start":"2026-10-16T00:25:18.217979Z","end":"2026-10-16T00:25:18.621017Z","steps":["trace[1007407330] 'agreement among raft nodes before linearized reading'  (duration: 402.18603ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.621259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:18.218728Z","time spent":"402.523577ms","remote":"127.0.0.1:40338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":2976,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" "}
{"level":"warn","ts":"2026-10-16T00:25:18.621288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:18.217953Z","time spent":"403.323034ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-10-16T00:25:18.620936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.351248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-10-16T00:25:18.621165Z","caller":"traceutil/trace.go:171","msg":"trace[481664100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8807; }","duration":"147.326365ms","start":"2026-10-16T00:25:18.473802Z","end":"2026-10-16T00:25:18.621129Z","steps":["trace[481664100] 'agreement among raft nodes before linearized reading'  (duration: 146.64677ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:18.621669Z","caller":"traceutil/trace.go:171","msg":"trace[530195240] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8807; }","duration":"134.787219ms","start":"2026-10-16T00:25:18.486861Z","end":"2026-10-16T00:25:18.621649Z","steps":["trace[530195240] 'agreement among raft nodes before linearized reading'  (duration: 133.991502ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.621885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.082358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-10-16T00:25:18.621954Z","caller":"traceutil/trace.go:171","msg":"trace[475495040] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8807; }","duration":"353.20686ms","start":"2026-10-16T00:25:18.268727Z","end":"2026-10-16T00:25:18.621934Z","steps":["trace[475495040] 'agreement among raft nodes before linearized reading'  (duration: 353.088918ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.622007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:25:18.268697Z","time spent":"353.290623ms","remote":"127.0.0.1:56016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":3,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "}
{"level":"info","ts":"2026-10-16T00:25:18.7639Z","caller":"traceutil/trace.go:171","msg":"trace[799230619] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"135.767151ms","start":"2026-10-16T00:25:18.62809Z","end":"2026-10-16T00:25:18.763857Z","steps":["trace[799230619] 'process raft request'  (duration: 57.348344ms)","trace[799230619] 'compare'  (duration: 78.210493ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:25:18.775722Z","caller":"traceutil/trace.go:171","msg":"trace[1945742971] transaction","detail":"{read_only:false; response_revision:8809; number_of_response:1; }","duration":"146.606059ms","start":"2026-10-16T00:25:18.629088Z","end":"2026-10-16T00:25:18.775695Z","steps":["trace[1945742971] 'process raft request'  (duration: 146.387834ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:18.776014Z","caller":"traceutil/trace.go:171","msg":"trace[94355395] transaction","detail":"{read_only:false; response_revision:8810; number_of_response:1; }","duration":"146.845054ms","start":"2026-10-16T00:25:18.629125Z","end":"2026-10-16T00:25:18.77597Z","steps":["trace[94355395] 'process raft request'  (duration: 146.517296ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:18.776123Z","caller":"traceutil/trace.go:171","msg":"trace[91149704] linearizableReadLoop","detail":"{readStateIndex:9224; appliedIndex:9218; }","duration":"110.139796ms","start":"2026-10-16T00:25:18.665965Z","end":"2026-10-16T00:25:18.776104Z","steps":["trace[91149704] 'read index received'  (duration: 19.566541ms)","trace[91149704] 'applied index is now lower than readState.Index'  (duration: 90.571944ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:25:18.776157Z","caller":"traceutil/trace.go:171","msg":"trace[1479417952] transaction","detail":"{read_only:false; response_revision:8813; number_of_response:1; }","duration":"139.815339ms","start":"2026-10-16T00:25:18.636326Z","end":"2026-10-16T00:25:18.776141Z","steps":["trace[1479417952] 'process raft request'  (duration: 139.709407ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:18.776092Z","caller":"traceutil/trace.go:171","msg":"trace[347506976] transaction","detail":"{read_only:false; response_revision:8811; number_of_response:1; }","duration":"146.751952ms","start":"2026-10-16T00:25:18.6293Z","end":"2026-10-16T00:25:18.776052Z","steps":["trace[347506976] 'process raft request'  (duration: 146.592149ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:25:18.776183Z","caller":"traceutil/trace.go:171","msg":"trace[1679329729] transaction","detail":"{read_only:false; response_revision:8812; number_of_response:1; }","duration":"146.831163ms","start":"2026-10-16T00:25:18.629314Z","end":"2026-10-16T00:25:18.776145Z","steps":["trace[1679329729] 'process raft request'  (duration: 146.67201ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:25:18.776338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.354901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.18dedad239f23f07\" ","response":"range_response_count:1 size:646"}
{"level":"info","ts":"2026-10-16T00:25:18.776376Z","caller":"traceutil/trace.go:171","msg":"trace[93171914] range","detail":"{range_begin:/registry/events/openstack/ceph.18dedad239f23f07; range_end:; response_count:1; response_revision:8813; }","duration":"110.473183ms","start":"2026-10-16T00:25:18.665894Z","end":"2026-10-16T00:25:18.776367Z","steps":["trace[93171914] 'agreement among raft nodes before linearized reading'  (duration: 110.272679ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:26:00.944278Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6479}
{"level":"info","ts":"2026-10-16T00:26:01.058306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6479,"took":"111.897461ms","hash":3302945115,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":25788416,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-10-16T00:26:01.058417Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3302945115,"revision":6479,"compact-revision":3142}
{"level":"info","ts":"2026-10-16T00:26:28.910691Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e368e6818bd6f7","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-10-16T00:26:28.917474Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-10-16T00:26:28.917641Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-10-16T00:31:00.952366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9251}
{"level":"info","ts":"2026-10-16T00:31:01.058144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9251,"took":"102.339473ms","hash":4167940984,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":28004352,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-10-16T00:31:01.058219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4167940984,"revision":9251,"compact-revision":6479}
{"level":"info","ts":"2026-10-16T00:36:00.963581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12168}
{"level":"info","ts":"2026-10-16T00:36:01.082349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12168,"took":"115.127665ms","hash":535576565,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":25673728,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-10-16T00:36:01.082433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":535576565,"revision":12168,"compact-revision":9251}
{"level":"info","ts":"2026-10-16T00:36:41.016799Z","caller":"traceutil/trace.go:171","msg":"trace[1310513284] transaction","detail":"{read_only:false; response_revision:14903; number_of_response:1; }","duration":"105.935741ms","start":"2026-10-16T00:36:40.910839Z","end":"2026-10-16T00:36:41.016775Z","steps":["trace[1310513284] 'process raft request'  (duration: 105.90157ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:36:41.016952Z","caller":"traceutil/trace.go:171","msg":"trace[1665350076] transaction","detail":"{read_only:false; response_revision:14901; number_of_response:1; }","duration":"109.94723ms","start":"2026-10-16T00:36:40.906998Z","end":"2026-10-16T00:36:41.016946Z","steps":["trace[1665350076] 'process raft request'  (duration: 105.995343ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:36:41.017002Z","caller":"traceutil/trace.go:171","msg":"trace[1813833658] transaction","detail":"{read_only:false; response_revision:14902; number_of_response:1; }","duration":"108.63653ms","start":"2026-10-16T00:36:40.908357Z","end":"2026-10-16T00:36:41.016993Z","steps":["trace[1813833658] 'process raft request'  (duration: 108.353754ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:36:51.202884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.799557ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409348300 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:14951 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:461 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-10-16T00:36:51.203083Z","caller":"traceutil/trace.go:171","msg":"trace[1003071154] linearizableReadLoop","detail":"{readStateIndex:15686; appliedIndex:15684; }","duration":"176.433416ms","start":"2026-10-16T00:36:51.026635Z","end":"2026-10-16T00:36:51.203069Z","steps":["trace[1003071154] 'read index received'  (duration: 45.611µs)","trace[1003071154] 'applied index is now lower than readState.Index'  (duration: 176.386585ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:36:51.203162Z","caller":"traceutil/trace.go:171","msg":"trace[1473869228] transaction","detail":"{read_only:false; response_revision:14970; number_of_response:1; }","duration":"287.738677ms","start":"2026-10-16T00:36:50.915414Z","end":"2026-10-16T00:36:51.203152Z","steps":["trace[1473869228] 'process raft request'  (duration: 287.568033ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:36:51.203122Z","caller":"traceutil/trace.go:171","msg":"trace[63004039] transaction","detail":"{read_only:false; response_revision:14969; number_of_response:1; }","duration":"292.081814ms","start":"2026-10-16T00:36:50.910996Z","end":"2026-10-16T00:36:51.203077Z","steps":["trace[63004039] 'process raft request'  (duration: 26.99126ms)","trace[63004039] 'compare'  (duration: 264.451309ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:36:51.203473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.827705ms","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-10-16T00:36:51.203569Z","caller":"traceutil/trace.go:171","msg":"trace[52893641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14970; }","duration":"176.976479ms","start":"2026-10-16T00:36:51.026569Z","end":"2026-10-16T00:36:51.203546Z","steps":["trace[52893641] 'agreement among raft nodes before linearized reading'  (duration: 176.712573ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:36:51.503072Z","caller":"traceutil/trace.go:171","msg":"trace[313546078] transaction","detail":"{read_only:false; response_revision:14974; number_of_response:1; }","duration":"293.797791ms","start":"2026-10-16T00:36:51.209243Z","end":"2026-10-16T00:36:51.503041Z","steps":["trace[313546078] 'process raft request'  (duration: 293.75462ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:36:51.503613Z","caller":"traceutil/trace.go:171","msg":"trace[1359405183] transaction","detail":"{read_only:false; response_revision:14972; number_of_response:1; }","duration":"294.832343ms","start":"2026-10-16T00:36:51.208769Z","end":"2026-10-16T00:36:51.503601Z","steps":["trace[1359405183] 'process raft request'  (duration: 294.011846ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:36:51.503758Z","caller":"traceutil/trace.go:171","msg":"trace[1005633170] transaction","detail":"{read_only:false; response_revision:14973; number_of_response:1; }","duration":"294.951747ms","start":"2026-10-16T00:36:51.208796Z","end":"2026-10-16T00:36:51.503748Z","steps":["trace[1005633170] 'process raft request'  (duration: 294.152319ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:38:24.544792Z","caller":"traceutil/trace.go:171","msg":"trace[1878079474] transaction","detail":"{read_only:false; response_revision:16254; number_of_response:1; }","duration":"103.560539ms","start":"2026-10-16T00:38:24.441143Z","end":"2026-10-16T00:38:24.544704Z","steps":["trace[1878079474] 'process raft request'  (duration: 69.540853ms)","trace[1878079474] 'compare'  (duration: 33.548265ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:38:24.544852Z","caller":"traceutil/trace.go:171","msg":"trace[1777872894] transaction","detail":"{read_only:false; response_revision:16255; number_of_response:1; }","duration":"101.901482ms","start":"2026-10-16T00:38:24.442909Z","end":"2026-10-16T00:38:24.54481Z","steps":["trace[1777872894] 'process raft request'  (duration: 101.723388ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:38:29.74727Z","caller":"traceutil/trace.go:171","msg":"trace[209627160] linearizableReadLoop","detail":"{readStateIndex:17054; appliedIndex:17053; }","duration":"130.757742ms","start":"2026-10-16T00:38:29.616455Z","end":"2026-10-16T00:38:29.747213Z","steps":["trace[209627160] 'read index received'  (duration: 58.623941ms)","trace[209627160] 'applied index is now lower than readState.Index'  (duration: 72.132031ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:38:29.747586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.103001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-10-16T00:38:29.747562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.198496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:38:29.747639Z","caller":"traceutil/trace.go:171","msg":"trace[675695491] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:16285; }","duration":"131.239023ms","start":"2026-10-16T00:38:29.616386Z","end":"2026-10-16T00:38:29.747625Z","steps":["trace[675695491] 'agreement among raft nodes before linearized reading'  (duration: 131.08495ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:38:29.747664Z","caller":"traceutil/trace.go:171","msg":"trace[675745188] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16285; }","duration":"119.331629ms","start":"2026-10-16T00:38:29.628308Z","end":"2026-10-16T00:38:29.74764Z","steps":["trace[675745188] 'agreement among raft nodes before linearized reading'  (duration: 119.174225ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:29.748154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.883443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5520"}
{"level":"info","ts":"2026-10-16T00:38:29.748195Z","caller":"traceutil/trace.go:171","msg":"trace[954693424] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16285; }","duration":"106.942524ms","start":"2026-10-16T00:38:29.641242Z","end":"2026-10-16T00:38:29.748184Z","steps":["trace[954693424] 'agreement among raft nodes before linearized reading'  (duration: 106.77825ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:29.7482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.51116ms","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-10-16T00:38:29.748266Z","caller":"traceutil/trace.go:171","msg":"trace[1400206454] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16285; }","duration":"118.628963ms","start":"2026-10-16T00:38:29.629623Z","end":"2026-10-16T00:38:29.748251Z","steps":["trace[1400206454] 'agreement among raft nodes before linearized reading'  (duration: 118.531791ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:29.748379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.678794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:38:29.748409Z","caller":"traceutil/trace.go:171","msg":"trace[1402008914] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16285; }","duration":"118.762055ms","start":"2026-10-16T00:38:29.629639Z","end":"2026-10-16T00:38:29.748401Z","steps":["trace[1402008914] 'agreement among raft nodes before linearized reading'  (duration: 118.713514ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:38:30.744655Z","caller":"traceutil/trace.go:171","msg":"trace[607377241] linearizableReadLoop","detail":"{readStateIndex:17061; appliedIndex:17060; }","duration":"240.673912ms","start":"2026-10-16T00:38:30.503952Z","end":"2026-10-16T00:38:30.744626Z","steps":["trace[607377241] 'read index received'  (duration: 240.390656ms)","trace[607377241] 'applied index is now lower than readState.Index'  (duration: 282.026µs)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:38:30.744769Z","caller":"traceutil/trace.go:171","msg":"trace[244666159] transaction","detail":"{read_only:false; response_revision:16291; number_of_response:1; }","duration":"359.888088ms","start":"2026-10-16T00:38:30.384826Z","end":"2026-10-16T00:38:30.744714Z","steps":["trace[244666159] 'process raft request'  (duration: 359.595572ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:30.744892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.919347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-10-16T00:38:30.744931Z","caller":"traceutil/trace.go:171","msg":"trace[786037398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16291; }","duration":"241.03606ms","start":"2026-10-16T00:38:30.503882Z","end":"2026-10-16T00:38:30.744918Z","steps":["trace[786037398] 'agreement among raft nodes before linearized reading'  (duration: 240.902557ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:30.744941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.828168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-10-16T00:38:30.744947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:38:30.384798Z","time spent":"360.053062ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16844,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" mod_revision:16290 > success:<request_put:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" value_size:16783 >> failure:<request_range:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" > >"}
{"level":"info","ts":"2026-10-16T00:38:30.744982Z","caller":"traceutil/trace.go:171","msg":"trace[998953056] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16291; }","duration":"182.92189ms","start":"2026-10-16T00:38:30.56205Z","end":"2026-10-16T00:38:30.744972Z","steps":["trace[998953056] 'agreement among raft nodes before linearized reading'  (duration: 182.819678ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:30.745297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.595591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"}
{"level":"info","ts":"2026-10-16T00:38:30.745318Z","caller":"traceutil/trace.go:171","msg":"trace[1997795226] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:16291; }","duration":"140.633011ms","start":"2026-10-16T00:38:30.604678Z","end":"2026-10-16T00:38:30.745311Z","steps":["trace[1997795226] 'agreement among raft nodes before linearized reading'  (duration: 140.498448ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:30.745385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.916908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"}
{"level":"info","ts":"2026-10-16T00:38:30.745419Z","caller":"traceutil/trace.go:171","msg":"trace[823010535] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16291; }","duration":"181.976399ms","start":"2026-10-16T00:38:30.563432Z","end":"2026-10-16T00:38:30.745408Z","steps":["trace[823010535] 'agreement among raft nodes before linearized reading'  (duration: 181.871457ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:30.745498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.03231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5520"}
{"level":"info","ts":"2026-10-16T00:38:30.745535Z","caller":"traceutil/trace.go:171","msg":"trace[1791321011] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16291; }","duration":"182.111032ms","start":"2026-10-16T00:38:30.563414Z","end":"2026-10-16T00:38:30.745525Z","steps":["trace[1791321011] 'agreement among raft nodes before linearized reading'  (duration: 181.962429ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:38:30.745607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.948149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"}
{"level":"info","ts":"2026-10-16T00:38:30.745631Z","caller":"traceutil/trace.go:171","msg":"trace[1408034831] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16291; }","duration":"141.01877ms","start":"2026-10-16T00:38:30.604603Z","end":"2026-10-16T00:38:30.745622Z","steps":["trace[1408034831] 'agreement among raft nodes before linearized reading'  (duration: 140.952539ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:41:00.97335Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14594}
{"level":"info","ts":"2026-10-16T00:41:01.072878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14594,"took":"95.997929ms","hash":3303811192,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":29818880,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-10-16T00:41:01.072981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3303811192,"revision":14594,"compact-revision":12168}
{"level":"info","ts":"2026-10-16T00:43:54.235911Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e368e6818bd6f7","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-10-16T00:43:54.243691Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-10-16T00:43:54.243857Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-10-16T00:44:16.73359Z","caller":"traceutil/trace.go:171","msg":"trace[1024177959] linearizableReadLoop","detail":"{readStateIndex:20266; appliedIndex:20265; }","duration":"308.210524ms","start":"2026-10-16T00:44:16.42534Z","end":"2026-10-16T00:44:16.73355Z","steps":["trace[1024177959] 'read index received'  (duration: 267.151917ms)","trace[1024177959] 'applied index is now lower than readState.Index'  (duration: 41.056487ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:44:16.733729Z","caller":"traceutil/trace.go:171","msg":"trace[1203770906] transaction","detail":"{read_only:false; response_revision:19344; number_of_response:1; }","duration":"310.7246ms","start":"2026-10-16T00:44:16.422961Z","end":"2026-10-16T00:44:16.733685Z","steps":["trace[1203770906] 'process raft request'  (duration: 269.631902ms)","trace[1203770906] 'compare'  (duration: 40.763061ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:44:16.734015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.906227ms","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":"warn","ts":"2026-10-16T00:44:16.734062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.87463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-q8fl7\" ","response":"range_response_count:1 size:20732"}
{"level":"info","ts":"2026-10-16T00:44:16.734086Z","caller":"traceutil/trace.go:171","msg":"trace[1578631328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19344; }","duration":"308.02229ms","start":"2026-10-16T00:44:16.426042Z","end":"2026-10-16T00:44:16.734064Z","steps":["trace[1578631328] 'agreement among raft nodes before linearized reading'  (duration: 307.761054ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:44:16.734114Z","caller":"traceutil/trace.go:171","msg":"trace[866618503] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-q8fl7; range_end:; response_count:1; response_revision:19344; }","duration":"251.983623ms","start":"2026-10-16T00:44:16.482116Z","end":"2026-10-16T00:44:16.7341Z","steps":["trace[866618503] 'agreement among raft nodes before linearized reading'  (duration: 251.748008ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:44:16.734146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:44:16.426029Z","time spent":"308.104411ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-10-16T00:44:16.734545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.95259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"}
{"level":"info","ts":"2026-10-16T00:44:16.734652Z","caller":"traceutil/trace.go:171","msg":"trace[1081165249] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:19344; }","duration":"309.382109ms","start":"2026-10-16T00:44:16.425244Z","end":"2026-10-16T00:44:16.734626Z","steps":["trace[1081165249] 'agreement among raft nodes before linearized reading'  (duration: 308.51748ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:44:16.734704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:44:16.425215Z","time spent":"309.477092ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":551,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "}
{"level":"warn","ts":"2026-10-16T00:44:16.734732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:44:16.422944Z","time spent":"310.882043ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:19327 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-10-16T00:44:16.862642Z","caller":"traceutil/trace.go:171","msg":"trace[262296354] transaction","detail":"{read_only:false; response_revision:19346; number_of_response:1; }","duration":"122.457203ms","start":"2026-10-16T00:44:16.740112Z","end":"2026-10-16T00:44:16.86257Z","steps":["trace[262296354] 'process raft request'  (duration: 122.147547ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:44:16.863263Z","caller":"traceutil/trace.go:171","msg":"trace[436812687] transaction","detail":"{read_only:false; response_revision:19347; number_of_response:1; }","duration":"104.520387ms","start":"2026-10-16T00:44:16.758701Z","end":"2026-10-16T00:44:16.863221Z","steps":["trace[436812687] 'process raft request'  (duration: 103.782831ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:46:00.98373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17596}
{"level":"info","ts":"2026-10-16T00:46:01.094968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17596,"took":"107.151681ms","hash":3253001440,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":34435072,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-10-16T00:46:01.09505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3253001440,"revision":17596,"compact-revision":14594}
{"level":"info","ts":"2026-10-16T00:51:00.992817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20475}
{"level":"info","ts":"2026-10-16T00:51:01.126922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20475,"took":"129.635619ms","hash":219542308,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":32825344,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-10-16T00:51:01.12708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":219542308,"revision":20475,"compact-revision":17596}
{"level":"info","ts":"2026-10-16T00:51:25.502087Z","caller":"traceutil/trace.go:171","msg":"trace[2132691808] linearizableReadLoop","detail":"{readStateIndex:24600; appliedIndex:24599; }","duration":"101.58358ms","start":"2026-10-16T00:51:25.400479Z","end":"2026-10-16T00:51:25.502062Z","steps":["trace[2132691808] 'read index received'  (duration: 101.374035ms)","trace[2132691808] 'applied index is now lower than readState.Index'  (duration: 207.925µs)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:51:25.502128Z","caller":"traceutil/trace.go:171","msg":"trace[2135574618] transaction","detail":"{read_only:false; response_revision:23484; number_of_response:1; }","duration":"266.811604ms","start":"2026-10-16T00:51:25.23528Z","end":"2026-10-16T00:51:25.502092Z","steps":["trace[2135574618] 'process raft request'  (duration: 266.602ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:25.502549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.015391ms","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-10-16T00:51:25.502852Z","caller":"traceutil/trace.go:171","msg":"trace[1655425978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23484; }","duration":"102.427589ms","start":"2026-10-16T00:51:25.400398Z","end":"2026-10-16T00:51:25.502826Z","steps":["trace[1655425978] 'agreement among raft nodes before linearized reading'  (duration: 101.780144ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:25.66935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.42059ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409375322 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:23474 > success:<request_put:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" value_size:549 >> failure:<request_range:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-10-16T00:51:25.669681Z","caller":"traceutil/trace.go:171","msg":"trace[367486236] linearizableReadLoop","detail":"{readStateIndex:24603; appliedIndex:24600; }","duration":"154.228311ms","start":"2026-10-16T00:51:25.515427Z","end":"2026-10-16T00:51:25.669655Z","steps":["trace[367486236] 'read index received'  (duration: 12.863714ms)","trace[367486236] 'applied index is now lower than readState.Index'  (duration: 141.362867ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:51:25.669822Z","caller":"traceutil/trace.go:171","msg":"trace[1892611760] transaction","detail":"{read_only:false; response_revision:23487; number_of_response:1; }","duration":"158.670359ms","start":"2026-10-16T00:51:25.511137Z","end":"2026-10-16T00:51:25.669807Z","steps":["trace[1892611760] 'process raft request'  (duration: 158.390122ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:25.669957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.507408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"}
{"level":"info","ts":"2026-10-16T00:51:25.670066Z","caller":"traceutil/trace.go:171","msg":"trace[1914835630] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23487; }","duration":"154.695422ms","start":"2026-10-16T00:51:25.515345Z","end":"2026-10-16T00:51:25.670041Z","steps":["trace[1914835630] 'agreement among raft nodes before linearized reading'  (duration: 154.374155ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:51:25.670253Z","caller":"traceutil/trace.go:171","msg":"trace[1256479856] transaction","detail":"{read_only:false; response_revision:23486; number_of_response:1; }","duration":"159.109948ms","start":"2026-10-16T00:51:25.511044Z","end":"2026-10-16T00:51:25.670154Z","steps":["trace[1256479856] 'process raft request'  (duration: 40.759228ms)","trace[1256479856] 'compare'  (duration: 117.107243ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:51:41.887865Z","caller":"traceutil/trace.go:171","msg":"trace[1943828006] linearizableReadLoop","detail":"{readStateIndex:24709; appliedIndex:24708; }","duration":"109.826614ms","start":"2026-10-16T00:51:41.778004Z","end":"2026-10-16T00:51:41.887831Z","steps":["trace[1943828006] 'read index received'  (duration: 49.356319ms)","trace[1943828006] 'applied index is now lower than readState.Index'  (duration: 60.469145ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:51:41.887924Z","caller":"traceutil/trace.go:171","msg":"trace[899442024] transaction","detail":"{read_only:false; response_revision:23587; number_of_response:1; }","duration":"200.893091ms","start":"2026-10-16T00:51:41.68699Z","end":"2026-10-16T00:51:41.887883Z","steps":["trace[899442024] 'process raft request'  (duration: 140.325184ms)","trace[899442024] 'compare'  (duration: 60.368582ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:51:41.888771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.733203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"}
{"level":"info","ts":"2026-10-16T00:51:41.888876Z","caller":"traceutil/trace.go:171","msg":"trace[1207427125] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:23587; }","duration":"110.906497ms","start":"2026-10-16T00:51:41.777938Z","end":"2026-10-16T00:51:41.888845Z","steps":["trace[1207427125] 'agreement among raft nodes before linearized reading'  (duration: 110.005387ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.158156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.515988ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409375605 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:23528 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >>","response":"size:18"}
{"level":"info","ts":"2026-10-16T00:51:42.158363Z","caller":"traceutil/trace.go:171","msg":"trace[752352255] transaction","detail":"{read_only:false; response_revision:23588; number_of_response:1; }","duration":"288.871831ms","start":"2026-10-16T00:51:41.869449Z","end":"2026-10-16T00:51:42.158321Z","steps":["trace[752352255] 'process raft request'  (duration: 183.066207ms)","trace[752352255] 'compare'  (duration: 105.14997ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:51:42.158492Z","caller":"traceutil/trace.go:171","msg":"trace[1548926519] transaction","detail":"{read_only:false; response_revision:23589; number_of_response:1; }","duration":"264.888342ms","start":"2026-10-16T00:51:41.893577Z","end":"2026-10-16T00:51:42.158465Z","steps":["trace[1548926519] 'process raft request'  (duration: 264.744068ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:51:42.158535Z","caller":"traceutil/trace.go:171","msg":"trace[399846719] linearizableReadLoop","detail":"{readStateIndex:24710; appliedIndex:24709; }","duration":"270.493135ms","start":"2026-10-16T00:51:41.887954Z","end":"2026-10-16T00:51:42.158447Z","steps":["trace[399846719] 'read index received'  (duration: 164.439006ms)","trace[399846719] 'applied index is now lower than readState.Index'  (duration: 106.049189ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:51:42.158863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.909994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"}
{"level":"warn","ts":"2026-10-16T00:51:42.158885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.907747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"}
{"level":"info","ts":"2026-10-16T00:51:42.158915Z","caller":"traceutil/trace.go:171","msg":"trace[1278938628] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23589; }","duration":"251.000485ms","start":"2026-10-16T00:51:41.907892Z","end":"2026-10-16T00:51:42.158893Z","steps":["trace[1278938628] 'agreement among raft nodes before linearized reading'  (duration: 250.824111ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:51:42.15893Z","caller":"traceutil/trace.go:171","msg":"trace[1286186940] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23589; }","duration":"282.030239ms","start":"2026-10-16T00:51:41.876885Z","end":"2026-10-16T00:51:42.158915Z","steps":["trace[1286186940] 'agreement among raft nodes before linearized reading'  (duration: 281.806394ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.15903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.013222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"}
{"level":"info","ts":"2026-10-16T00:51:42.159086Z","caller":"traceutil/trace.go:171","msg":"trace[2132789844] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23589; }","duration":"240.121685ms","start":"2026-10-16T00:51:41.918946Z","end":"2026-10-16T00:51:42.159068Z","steps":["trace[2132789844] 'agreement among raft nodes before linearized reading'  (duration: 239.92979ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.483907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.727142ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409375614 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:23580 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-10-16T00:51:42.484054Z","caller":"traceutil/trace.go:171","msg":"trace[362100263] transaction","detail":"{read_only:false; response_revision:23590; number_of_response:1; }","duration":"315.921066ms","start":"2026-10-16T00:51:42.168104Z","end":"2026-10-16T00:51:42.484025Z","steps":["trace[362100263] 'process raft request'  (duration: 199.925279ms)","trace[362100263] 'compare'  (duration: 115.525667ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:51:42.484146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.168079Z","time spent":"316.033749ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" mod_revision:23580 > success:<request_put:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" value_size:562 >> failure:<request_range:<key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-10-16T00:51:42.66273Z","caller":"traceutil/trace.go:171","msg":"trace[2088091681] transaction","detail":"{read_only:false; response_revision:23591; number_of_response:1; }","duration":"493.105885ms","start":"2026-10-16T00:51:42.169578Z","end":"2026-10-16T00:51:42.662684Z","steps":["trace[2088091681] 'process raft request'  (duration: 426.24363ms)","trace[2088091681] 'compare'  (duration: 66.666121ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:51:42.662998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.169558Z","time spent":"493.32113ms","remote":"127.0.0.1:33352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:23578 > success:<request_put:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" value_size:549 >> failure:<request_range:<key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-10-16T00:51:42.662997Z","caller":"traceutil/trace.go:171","msg":"trace[1446604907] transaction","detail":"{read_only:false; response_revision:23592; number_of_response:1; }","duration":"421.207289ms","start":"2026-10-16T00:51:42.24176Z","end":"2026-10-16T00:51:42.662967Z","steps":["trace[1446604907] 'process raft request'  (duration: 420.892162ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.66317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.241685Z","time spent":"421.397714ms","remote":"127.0.0.1:50484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" mod_revision:23561 > success:<request_put:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" value_size:16370 >> failure:<request_range:<key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" > >"}
{"level":"info","ts":"2026-10-16T00:51:42.663234Z","caller":"traceutil/trace.go:171","msg":"trace[1334750079] linearizableReadLoop","detail":"{readStateIndex:24713; appliedIndex:24711; }","duration":"478.262257ms","start":"2026-10-16T00:51:42.184937Z","end":"2026-10-16T00:51:42.6632Z","steps":["trace[1334750079] 'read index received'  (duration: 183.044977ms)","trace[1334750079] 'applied index is now lower than readState.Index'  (duration: 295.21536ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:51:42.663307Z","caller":"traceutil/trace.go:171","msg":"trace[125429113] transaction","detail":"{read_only:false; response_revision:23593; number_of_response:1; }","duration":"415.626666ms","start":"2026-10-16T00:51:42.24767Z","end":"2026-10-16T00:51:42.663296Z","steps":["trace[125429113] 'process raft request'  (duration: 415.237068ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.663356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.247657Z","time spent":"415.671607ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:23583 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-10-16T00:51:42.663608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.496021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-10-16T00:51:42.663632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.357057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"}
{"level":"warn","ts":"2026-10-16T00:51:42.663648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.691016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"warn","ts":"2026-10-16T00:51:42.663713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.160339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-10-16T00:51:42.663751Z","caller":"traceutil/trace.go:171","msg":"trace[1934036826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23593; }","duration":"158.250711ms","start":"2026-10-16T00:51:42.50549Z","end":"2026-10-16T00:51:42.663741Z","steps":["trace[1934036826] 'agreement among raft nodes before linearized reading'  (duration: 158.190359ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.663619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.724674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-10-16T00:51:42.663833Z","caller":"traceutil/trace.go:171","msg":"trace[1456314117] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:23593; }","duration":"326.039611ms","start":"2026-10-16T00:51:42.337777Z","end":"2026-10-16T00:51:42.663817Z","steps":["trace[1456314117] 'agreement among raft nodes before linearized reading'  (duration: 325.723354ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.663884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.337746Z","time spent":"326.114453ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "}
{"level":"info","ts":"2026-10-16T00:51:42.663746Z","caller":"traceutil/trace.go:171","msg":"trace[1229412646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23593; }","duration":"478.850241ms","start":"2026-10-16T00:51:42.184877Z","end":"2026-10-16T00:51:42.663727Z","steps":["trace[1229412646] 'agreement among raft nodes before linearized reading'  (duration: 478.482092ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.663979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.184865Z","time spent":"479.101997ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-10-16T00:51:42.663696Z","caller":"traceutil/trace.go:171","msg":"trace[295388399] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:23593; }","duration":"175.46319ms","start":"2026-10-16T00:51:42.488212Z","end":"2026-10-16T00:51:42.663675Z","steps":["trace[295388399] 'agreement among raft nodes before linearized reading'  (duration: 175.273295ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:51:42.663651Z","caller":"traceutil/trace.go:171","msg":"trace[441925552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23593; }","duration":"414.590593ms","start":"2026-10-16T00:51:42.24905Z","end":"2026-10-16T00:51:42.66364Z","steps":["trace[441925552] 'agreement among raft nodes before linearized reading'  (duration: 414.429569ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.664121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.249027Z","time spent":"415.084586ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-10-16T00:51:42.663697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.578982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-10-16T00:51:42.664215Z","caller":"traceutil/trace.go:171","msg":"trace[1103421795] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:23593; }","duration":"365.138904ms","start":"2026-10-16T00:51:42.299061Z","end":"2026-10-16T00:51:42.6642Z","steps":["trace[1103421795] 'agreement among raft nodes before linearized reading'  (duration: 364.605562ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.664252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T00:51:42.299041Z","time spent":"365.199276ms","remote":"127.0.0.1:59990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "}
{"level":"info","ts":"2026-10-16T00:51:42.821893Z","caller":"traceutil/trace.go:171","msg":"trace[187774384] transaction","detail":"{read_only:false; response_revision:23595; number_of_response:1; }","duration":"151.48088ms","start":"2026-10-16T00:51:42.67037Z","end":"2026-10-16T00:51:42.821851Z","steps":["trace[187774384] 'process raft request'  (duration: 151.091402ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:51:42.822015Z","caller":"traceutil/trace.go:171","msg":"trace[830848687] transaction","detail":"{read_only:false; response_revision:23596; number_of_response:1; }","duration":"151.257326ms","start":"2026-10-16T00:51:42.670728Z","end":"2026-10-16T00:51:42.821985Z","steps":["trace[830848687] 'process raft request'  (duration: 150.96323ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:51:42.822003Z","caller":"traceutil/trace.go:171","msg":"trace[1863681441] linearizableReadLoop","detail":"{readStateIndex:24720; appliedIndex:24716; }","duration":"146.236315ms","start":"2026-10-16T00:51:42.67574Z","end":"2026-10-16T00:51:42.821976Z","steps":["trace[1863681441] 'read index received'  (duration: 69.048932ms)","trace[1863681441] 'applied index is now lower than readState.Index'  (duration: 77.186413ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:51:42.822155Z","caller":"traceutil/trace.go:171","msg":"trace[331552474] transaction","detail":"{read_only:false; response_revision:23597; number_of_response:1; }","duration":"151.345797ms","start":"2026-10-16T00:51:42.670791Z","end":"2026-10-16T00:51:42.822137Z","steps":["trace[331552474] 'process raft request'  (duration: 150.976029ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:51:42.822166Z","caller":"traceutil/trace.go:171","msg":"trace[935779135] transaction","detail":"{read_only:false; number_of_response:0; response_revision:23597; }","duration":"149.157689ms","start":"2026-10-16T00:51:42.672995Z","end":"2026-10-16T00:51:42.822153Z","steps":["trace[935779135] 'process raft request'  (duration: 148.924044ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:51:42.822222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.456169ms","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-10-16T00:51:42.822278Z","caller":"traceutil/trace.go:171","msg":"trace[210034489] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23597; }","duration":"146.559782ms","start":"2026-10-16T00:51:42.675701Z","end":"2026-10-16T00:51:42.822261Z","steps":["trace[210034489] 'agreement among raft nodes before linearized reading'  (duration: 146.359548ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:55:22.59108Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006afc.wal"}
{"level":"info","ts":"2026-10-16T00:55:29.112354Z","caller":"traceutil/trace.go:171","msg":"trace[805691386] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"178.576928ms","start":"2026-10-16T00:55:28.933575Z","end":"2026-10-16T00:55:29.112152Z","steps":["trace[805691386] 'process raft request'  (duration: 178.078457ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:56:01.000419Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23324}
{"level":"info","ts":"2026-10-16T00:56:01.126443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23324,"took":"120.587779ms","hash":769806755,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":45056000,"current-db-size-in-use":"45 MB"}
{"level":"info","ts":"2026-10-16T00:56:01.12653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":769806755,"revision":23324,"compact-revision":20475}
{"level":"info","ts":"2026-10-16T00:56:58.573803Z","caller":"traceutil/trace.go:171","msg":"trace[793921462] transaction","detail":"{read_only:false; response_revision:27386; number_of_response:1; }","duration":"133.897593ms","start":"2026-10-16T00:56:58.439863Z","end":"2026-10-16T00:56:58.573761Z","steps":["trace[793921462] 'process raft request'  (duration: 43.956629ms)","trace[793921462] 'compare'  (duration: 89.76166ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:56:58.5877Z","caller":"traceutil/trace.go:171","msg":"trace[2076926294] transaction","detail":"{read_only:false; response_revision:27387; number_of_response:1; }","duration":"146.552293ms","start":"2026-10-16T00:56:58.441123Z","end":"2026-10-16T00:56:58.587675Z","steps":["trace[2076926294] 'process raft request'  (duration: 146.294567ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:56:58.587682Z","caller":"traceutil/trace.go:171","msg":"trace[586008985] linearizableReadLoop","detail":"{readStateIndex:28668; appliedIndex:28666; }","duration":"124.828483ms","start":"2026-10-16T00:56:58.462808Z","end":"2026-10-16T00:56:58.587636Z","steps":["trace[586008985] 'read index received'  (duration: 21.040163ms)","trace[586008985] 'applied index is now lower than readState.Index'  (duration: 103.7868ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:56:58.589481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.641673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11501"}
{"level":"info","ts":"2026-10-16T00:56:58.589582Z","caller":"traceutil/trace.go:171","msg":"trace[1319138428] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27387; }","duration":"126.784537ms","start":"2026-10-16T00:56:58.462775Z","end":"2026-10-16T00:56:58.589559Z","steps":["trace[1319138428] 'agreement among raft nodes before linearized reading'  (duration: 125.023998ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:58:58.274053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.126744ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15490026748409389425 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:28649 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:504 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-10-16T00:58:58.274297Z","caller":"traceutil/trace.go:171","msg":"trace[1501996711] transaction","detail":"{read_only:false; response_revision:28668; number_of_response:1; }","duration":"203.704099ms","start":"2026-10-16T00:58:58.070531Z","end":"2026-10-16T00:58:58.274235Z","steps":["trace[1501996711] 'process raft request'  (duration: 69.246496ms)","trace[1501996711] 'compare'  (duration: 133.91327ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:58:58.572531Z","caller":"traceutil/trace.go:171","msg":"trace[440457390] transaction","detail":"{read_only:false; response_revision:28669; number_of_response:1; }","duration":"131.022747ms","start":"2026-10-16T00:58:58.44147Z","end":"2026-10-16T00:58:58.572493Z","steps":["trace[440457390] 'process raft request'  (duration: 130.735971ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T00:58:58.715739Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e368e6818bd6f7","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-10-16T00:58:58.716029Z","caller":"traceutil/trace.go:171","msg":"trace[1697279459] transaction","detail":"{read_only:false; response_revision:28670; number_of_response:1; }","duration":"137.339325ms","start":"2026-10-16T00:58:58.578635Z","end":"2026-10-16T00:58:58.715974Z","steps":["trace[1697279459] 'process raft request'  (duration: 128.710716ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T00:58:58.881568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.056225ms","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-10-16T00:58:58.881703Z","caller":"traceutil/trace.go:171","msg":"trace[309227194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28670; }","duration":"219.24455ms","start":"2026-10-16T00:58:58.662423Z","end":"2026-10-16T00:58:58.881668Z","steps":["trace[309227194] 'agreement among raft nodes before linearized reading'  (duration: 53.750334ms)","trace[309227194] 'range keys from in-memory index tree'  (duration: 165.159329ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T00:58:58.881759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.912529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-10-16T00:58:58.881907Z","caller":"traceutil/trace.go:171","msg":"trace[820054770] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:28670; }","duration":"242.145304ms","start":"2026-10-16T00:58:58.639729Z","end":"2026-10-16T00:58:58.881875Z","steps":["trace[820054770] 'agreement among raft nodes before linearized reading'  (duration: 76.370622ms)","trace[820054770] 'count revisions from in-memory index tree'  (duration: 165.516346ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:58:59.058198Z","caller":"traceutil/trace.go:171","msg":"trace[534139149] transaction","detail":"{read_only:false; response_revision:28673; number_of_response:1; }","duration":"114.678756ms","start":"2026-10-16T00:58:58.943481Z","end":"2026-10-16T00:58:59.05816Z","steps":["trace[534139149] 'process raft request'  (duration: 96.389373ms)","trace[534139149] 'compare'  (duration: 17.734861ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:58:59.05796Z","caller":"traceutil/trace.go:171","msg":"trace[148310329] linearizableReadLoop","detail":"{readStateIndex:30006; appliedIndex:30005; }","duration":"114.110182ms","start":"2026-10-16T00:58:58.943808Z","end":"2026-10-16T00:58:59.057918Z","steps":["trace[148310329] 'read index received'  (duration: 96.063995ms)","trace[148310329] 'applied index is now lower than readState.Index'  (duration: 18.044627ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T00:58:59.058696Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003}
{"level":"warn","ts":"2026-10-16T00:58:59.058787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.949492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"}
{"level":"info","ts":"2026-10-16T00:58:59.058871Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}
{"level":"info","ts":"2026-10-16T00:58:59.058898Z","caller":"traceutil/trace.go:171","msg":"trace[1260951012] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28673; }","duration":"115.156216ms","start":"2026-10-16T00:58:58.943722Z","end":"2026-10-16T00:58:59.058879Z","steps":["trace[1260951012] 'agreement among raft nodes before linearized reading'  (duration: 114.822919ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T01:00:01.010065Z","caller":"traceutil/trace.go:171","msg":"trace[779048369] transaction","detail":"{read_only:false; response_revision:29348; number_of_response:1; }","duration":"353.203431ms","start":"2026-10-16T01:00:00.656826Z","end":"2026-10-16T01:00:01.01003Z","steps":["trace[779048369] 'process raft request'  (duration: 353.031277ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T01:00:01.010271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:00.656804Z","time spent":"353.368015ms","remote":"127.0.0.1:58280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2275,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/cilium.io/ciliumidentities/50138\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumidentities/50138\" value_size:2225 >> failure:<>"}
{"level":"info","ts":"2026-10-16T01:00:01.011515Z","caller":"traceutil/trace.go:171","msg":"trace[1844867055] linearizableReadLoop","detail":"{readStateIndex:30708; appliedIndex:30706; }","duration":"105.26091ms","start":"2026-10-16T01:00:00.906219Z","end":"2026-10-16T01:00:01.01148Z","steps":["trace[1844867055] 'read index received'  (duration: 103.771146ms)","trace[1844867055] 'applied index is now lower than readState.Index'  (duration: 1.488424ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T01:00:01.011978Z","caller":"traceutil/trace.go:171","msg":"trace[2008282498] transaction","detail":"{read_only:false; response_revision:29349; number_of_response:1; }","duration":"112.283844ms","start":"2026-10-16T01:00:00.899676Z","end":"2026-10-16T01:00:01.01196Z","steps":["trace[2008282498] 'process raft request'  (duration: 111.526698ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T01:00:01.012448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.20477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"}
{"level":"info","ts":"2026-10-16T01:00:01.012491Z","caller":"traceutil/trace.go:171","msg":"trace[716012569] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:29350; }","duration":"106.296592ms","start":"2026-10-16T01:00:00.906179Z","end":"2026-10-16T01:00:01.012475Z","steps":["trace[716012569] 'agreement among raft nodes before linearized reading'  (duration: 106.130499ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T01:00:01.240542Z","caller":"traceutil/trace.go:171","msg":"trace[1510120579] transaction","detail":"{read_only:false; response_revision:29353; number_of_response:1; }","duration":"213.723409ms","start":"2026-10-16T01:00:01.026773Z","end":"2026-10-16T01:00:01.240496Z","steps":["trace[1510120579] 'process raft request'  (duration: 213.591385ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T01:00:01.240579Z","caller":"traceutil/trace.go:171","msg":"trace[29172257] transaction","detail":"{read_only:false; response_revision:29352; number_of_response:1; }","duration":"214.928585ms","start":"2026-10-16T01:00:01.025611Z","end":"2026-10-16T01:00:01.24054Z","steps":["trace[29172257] 'process raft request'  (duration: 131.302583ms)","trace[29172257] 'compare'  (duration: 83.289484ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T01:00:01.429457Z","caller":"traceutil/trace.go:171","msg":"trace[1631057397] transaction","detail":"{read_only:false; response_revision:29354; number_of_response:1; }","duration":"182.239164ms","start":"2026-10-16T01:00:01.247171Z","end":"2026-10-16T01:00:01.42941Z","steps":["trace[1631057397] 'process raft request'  (duration: 143.898839ms)","trace[1631057397] 'compare'  (duration: 38.09351ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T01:00:01.690343Z","caller":"traceutil/trace.go:171","msg":"trace[1867917090] linearizableReadLoop","detail":"{readStateIndex:30714; appliedIndex:30712; }","duration":"337.592857ms","start":"2026-10-16T01:00:01.352719Z","end":"2026-10-16T01:00:01.690312Z","steps":["trace[1867917090] 'read index received'  (duration: 38.442886ms)","trace[1867917090] 'applied index is now lower than readState.Index'  (duration: 299.148171ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T01:00:01.690442Z","caller":"traceutil/trace.go:171","msg":"trace[1070431880] transaction","detail":"{read_only:false; response_revision:29355; number_of_response:1; }","duration":"437.170441ms","start":"2026-10-16T01:00:01.253222Z","end":"2026-10-16T01:00:01.690393Z","steps":["trace[1070431880] 'process raft request'  (duration: 412.610149ms)","trace[1070431880] 'compare'  (duration: 24.290986ms)"],"step_count":2}
{"level":"warn","ts":"2026-10-16T01:00:01.690622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:01.25321Z","time spent":"437.326124ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2908,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/cilium.io/ciliumendpoints/openstack/senlin-engine-cleaner-29868540-gzmtt\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumendpoints/openstack/senlin-engine-cleaner-29868540-gzmtt\" value_size:2818 >> failure:<>"}
{"level":"warn","ts":"2026-10-16T01:00:01.690748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.998245ms","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-10-16T01:00:01.690869Z","caller":"traceutil/trace.go:171","msg":"trace[390048323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:29355; }","duration":"338.167509ms","start":"2026-10-16T01:00:01.352668Z","end":"2026-10-16T01:00:01.690836Z","steps":["trace[390048323] 'agreement among raft nodes before linearized reading'  (duration: 337.809822ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T01:00:01.690916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:01.352648Z","time spent":"338.254131ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-10-16T01:00:01.847184Z","caller":"traceutil/trace.go:171","msg":"trace[1951917054] linearizableReadLoop","detail":"{readStateIndex:30716; appliedIndex:30714; }","duration":"156.729033ms","start":"2026-10-16T01:00:01.690432Z","end":"2026-10-16T01:00:01.847161Z","steps":["trace[1951917054] 'read index received'  (duration: 152.063939ms)","trace[1951917054] 'applied index is now lower than readState.Index'  (duration: 4.660354ms)"],"step_count":2}
{"level":"info","ts":"2026-10-16T01:00:01.847195Z","caller":"traceutil/trace.go:171","msg":"trace[1603036621] transaction","detail":"{read_only:false; response_revision:29356; number_of_response:1; }","duration":"447.455378ms","start":"2026-10-16T01:00:01.399707Z","end":"2026-10-16T01:00:01.847162Z","steps":["trace[1603036621] 'process raft request'  (duration: 442.844506ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T01:00:01.847293Z","caller":"traceutil/trace.go:171","msg":"trace[2047985336] transaction","detail":"{read_only:false; response_revision:29357; number_of_response:1; }","duration":"407.530088ms","start":"2026-10-16T01:00:01.439688Z","end":"2026-10-16T01:00:01.847218Z","steps":["trace[2047985336] 'process raft request'  (duration: 407.353684ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T01:00:01.847455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:01.399685Z","time spent":"447.616822ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:29298 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"warn","ts":"2026-10-16T01:00:01.847469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.752965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:528"}
{"level":"info","ts":"2026-10-16T01:00:01.847503Z","caller":"traceutil/trace.go:171","msg":"trace[206167670] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:29357; }","duration":"388.861397ms","start":"2026-10-16T01:00:01.458632Z","end":"2026-10-16T01:00:01.847494Z","steps":["trace[206167670] 'agreement among raft nodes before linearized reading'  (duration: 388.632432ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T01:00:01.847492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.504213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-10-16T01:00:01.847533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:01.458602Z","time spent":"388.923909ms","remote":"127.0.0.1:33560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":551,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "}
{"level":"warn","ts":"2026-10-16T01:00:01.847521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:01.439653Z","time spent":"407.771922ms","remote":"127.0.0.1:58242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2880,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/cilium.io/ciliumendpoints/openstack/heat-engine-cleaner-29868540-nhqkx\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumendpoints/openstack/heat-engine-cleaner-29868540-nhqkx\" value_size:2792 >> failure:<>"}
{"level":"warn","ts":"2026-10-16T01:00:01.847484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.110031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-10-16T01:00:01.84756Z","caller":"traceutil/trace.go:171","msg":"trace[499546934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29357; }","duration":"341.657067ms","start":"2026-10-16T01:00:01.505884Z","end":"2026-10-16T01:00:01.847541Z","steps":["trace[499546934] 'agreement among raft nodes before linearized reading'  (duration: 341.389301ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T01:00:01.847612Z","caller":"traceutil/trace.go:171","msg":"trace[634289696] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:29357; }","duration":"359.311866ms","start":"2026-10-16T01:00:01.488274Z","end":"2026-10-16T01:00:01.847586Z","steps":["trace[634289696] 'agreement among raft nodes before linearized reading'  (duration: 359.04029ms)"],"step_count":1}
{"level":"warn","ts":"2026-10-16T01:00:01.847613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:01.505862Z","time spent":"341.739608ms","remote":"127.0.0.1:33256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-10-16T01:00:01.847672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-16T01:00:01.488223Z","time spent":"359.436708ms","remote":"127.0.0.1:35950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "}
{"level":"info","ts":"2026-10-16T01:00:01.857891Z","caller":"traceutil/trace.go:171","msg":"trace[1823979483] transaction","detail":"{read_only:false; response_revision:29358; number_of_response:1; }","duration":"162.222954ms","start":"2026-10-16T01:00:01.695629Z","end":"2026-10-16T01:00:01.857852Z","steps":["trace[1823979483] 'process raft request'  (duration: 161.943868ms)"],"step_count":1}
{"level":"info","ts":"2026-10-16T01:01:01.006736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26698}
{"level":"info","ts":"2026-10-16T01:01:01.168333Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26698,"took":"155.403274ms","hash":3654228742,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":46800896,"current-db-size-in-use":"47 MB"}
{"level":"info","ts":"2026-10-16T01:01:01.168437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3654228742,"revision":26698,"compact-revision":23324}
{"level":"info","ts":"2026-10-16T01:06:01.014343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30139}
{"level":"info","ts":"2026-10-16T01:06:01.177431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30139,"took":"158.160635ms","hash":677143210,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":37863424,"current-db-size-in-use":"38 MB"}
{"level":"info","ts":"2026-10-16T01:06:01.177506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":677143210,"revision":30139,"compact-revision":26698}
{"level":"info","ts":"2026-10-16T01:11:01.02403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33309}
{"level":"info","ts":"2026-10-16T01:11:01.183495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33309,"took":"154.356092ms","hash":98466023,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":35000320,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-10-16T01:11:01.183578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":98466023,"revision":33309,"compact-revision":30139}
{"level":"info","ts":"2026-10-16T01:15:12.323776Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e368e6818bd6f7","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-10-16T01:15:12.330353Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004}
{"level":"info","ts":"2026-10-16T01:15:12.330918Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}
{"level":"info","ts":"2026-10-16T01:16:01.032603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35977}
{"level":"info","ts":"2026-10-16T01:16:01.18379Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35977,"took":"146.26438ms","hash":2803261420,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":34598912,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-10-16T01:16:01.183871Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2803261420,"revision":35977,"compact-revision":33309}
{"level":"info","ts":"2026-10-16T01:21:01.04003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38685}
{"level":"info","ts":"2026-10-16T01:21:01.21992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38685,"took":"173.700724ms","hash":1014204626,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":35241984,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-10-16T01:21:01.220023Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1014204626,"revision":38685,"compact-revision":35977}
{"level":"info","ts":"2026-10-16T01:26:01.047726Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41428}
{"level":"info","ts":"2026-10-16T01:26:01.215673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41428,"took":"159.889989ms","hash":41192491,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":34426880,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-10-16T01:26:01.215753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":41192491,"revision":41428,"compact-revision":38685}
{"level":"info","ts":"2026-10-16T01:31:01.05682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":44101}
{"level":"info","ts":"2026-10-16T01:31:01.209109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":44101,"took":"144.977641ms","hash":4281534205,"current-db-size-bytes":60530688,"current-db-size":"60 MB","current-db-size-in-use-bytes":33128448,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-10-16T01:31:01.20918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4281534205,"revision":44101,"compact-revision":41428}
{"level":"info","ts":"2026-10-16T01:32:53.82614Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f1e368e6818bd6f7","local-member-applied-index":50005,"local-member-snapshot-index":40004,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-10-16T01:32:53.832331Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":50005}
{"level":"info","ts":"2026-10-16T01:32:53.832467Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":45005}