{"level":"warn","ts":"2026-03-09T01:40:54.516704Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-09T01:40:54.516827Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-03-09T01:40:54.516929Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-09T01:40:54.516955Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]}
{"level":"info","ts":"2026-03-09T01:40:54.516988Z","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-03-09T01:40:54.518362Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]}
{"level":"info","ts":"2026-03-09T01:40:54.518458Z","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://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-03-09T01:40:54.523035Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.334392ms"}
{"level":"info","ts":"2026-03-09T01:40:54.531168Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"}
{"level":"info","ts":"2026-03-09T01:40:54.531285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-09T01:40:54.531323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"}
{"level":"info","ts":"2026-03-09T01:40:54.531382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-09T01:40:54.531401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"}
{"level":"info","ts":"2026-03-09T01:40:54.531524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"}
{"level":"warn","ts":"2026-03-09T01:40:54.542524Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-09T01:40:54.547086Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-09T01:40:54.549479Z","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-03-09T01:40:54.552772Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-09T01:40:54.552947Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-09T01:40:54.55309Z","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-03-09T01:40:54.553149Z","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-03-09T01:40:54.553163Z","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-03-09T01:40:54.554164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"}
{"level":"info","ts":"2026-03-09T01:40:54.55427Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]}
{"level":"info","ts":"2026-03-09T01:40:54.556973Z","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-03-09T01:40:54.557189Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"}
{"level":"info","ts":"2026-03-09T01:40:54.557257Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"}
{"level":"info","ts":"2026-03-09T01:40:54.557297Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-09T01:40:54.557344Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-09T01:40:55.132548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-09T01:40:55.132735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-09T01:40:55.132908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"}
{"level":"info","ts":"2026-03-09T01:40:55.132971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"}
{"level":"info","ts":"2026-03-09T01:40:55.133005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"}
{"level":"info","ts":"2026-03-09T01:40:55.133044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"}
{"level":"info","ts":"2026-03-09T01:40:55.133089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"}
{"level":"info","ts":"2026-03-09T01:40:55.134543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T01:40:55.137011Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-09T01:40:55.137094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-09T01:40:55.137044Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-09T01:40:55.137417Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-09T01:40:55.13748Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-09T01:40:55.140797Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-09T01:40:55.140878Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"}
{"level":"info","ts":"2026-03-09T01:40:55.141422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T01:40:55.141613Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T01:40:55.141666Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T01:41:11.495571Z","caller":"traceutil/trace.go:171","msg":"trace[1771078017] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"157.651985ms","start":"2026-03-09T01:41:11.33788Z","end":"2026-03-09T01:41:11.495531Z","steps":["trace[1771078017] 'read index received'  (duration: 157.493451ms)","trace[1771078017] 'applied index is now lower than readState.Index'  (duration: 157.414µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:41:11.495788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.886161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-03-09T01:41:11.49584Z","caller":"traceutil/trace.go:171","msg":"trace[530775300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"158.048715ms","start":"2026-03-09T01:41:11.33778Z","end":"2026-03-09T01:41:11.495828Z","steps":["trace[530775300] 'agreement among raft nodes before linearized reading'  (duration: 157.893111ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:41:11.496088Z","caller":"traceutil/trace.go:171","msg":"trace[188636954] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"250.204157ms","start":"2026-03-09T01:41:11.245851Z","end":"2026-03-09T01:41:11.496055Z","steps":["trace[188636954] 'process raft request'  (duration: 249.513081ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:41:34.168789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.615359ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7967603109031479228 > lease_revoke:<id:6e929cd0411e86f6>","response":"size:28"}
{"level":"info","ts":"2026-03-09T01:41:34.168996Z","caller":"traceutil/trace.go:171","msg":"trace[1279872244] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"562.301598ms","start":"2026-03-09T01:41:33.606616Z","end":"2026-03-09T01:41:34.168918Z","steps":["trace[1279872244] 'read index received'  (duration: 320.517132ms)","trace[1279872244] 'applied index is now lower than readState.Index'  (duration: 241.780336ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:41:34.169263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.220042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-03-09T01:41:34.169323Z","caller":"traceutil/trace.go:171","msg":"trace[1458317605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"508.303214ms","start":"2026-03-09T01:41:33.660991Z","end":"2026-03-09T01:41:34.169295Z","steps":["trace[1458317605] 'agreement among raft nodes before linearized reading'  (duration: 508.1374ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:41:34.169385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:33.660978Z","time spent":"508.395966ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-09T01:41:34.169532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.833071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-09T01:41:34.169862Z","caller":"traceutil/trace.go:171","msg":"trace[1973731277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"563.099737ms","start":"2026-03-09T01:41:33.606549Z","end":"2026-03-09T01:41:34.169649Z","steps":["trace[1973731277] 'agreement among raft nodes before linearized reading'  (duration: 562.596435ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:41:34.170021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:33.606522Z","time spent":"563.467785ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-09T01:41:34.169688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.587537ms","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-03-09T01:41:34.17039Z","caller":"traceutil/trace.go:171","msg":"trace[121200708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"493.329524ms","start":"2026-03-09T01:41:33.67699Z","end":"2026-03-09T01:41:34.170319Z","steps":["trace[121200708] 'agreement among raft nodes before linearized reading'  (duration: 492.301451ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:41:34.170501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:33.676961Z","time spent":"493.492257ms","remote":"127.0.0.1:43094","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":"info","ts":"2026-03-09T01:41:38.435099Z","caller":"traceutil/trace.go:171","msg":"trace[517991268] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"108.754847ms","start":"2026-03-09T01:41:38.326316Z","end":"2026-03-09T01:41:38.435071Z","steps":["trace[517991268] 'read index received'  (duration: 54.426455ms)","trace[517991268] 'applied index is now lower than readState.Index'  (duration: 54.327342ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:41:38.435173Z","caller":"traceutil/trace.go:171","msg":"trace[1424634604] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"122.54219ms","start":"2026-03-09T01:41:38.3126Z","end":"2026-03-09T01:41:38.435142Z","steps":["trace[1424634604] 'process raft request'  (duration: 68.147216ms)","trace[1424634604] 'compare'  (duration: 54.181879ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:41:38.435391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.052864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"}
{"level":"info","ts":"2026-03-09T01:41:38.435439Z","caller":"traceutil/trace.go:171","msg":"trace[813740619] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:738; }","duration":"109.145266ms","start":"2026-03-09T01:41:38.326275Z","end":"2026-03-09T01:41:38.435421Z","steps":["trace[813740619] 'agreement among raft nodes before linearized reading'  (duration: 108.907071ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:41:53.114194Z","caller":"traceutil/trace.go:171","msg":"trace[122856082] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"388.142728ms","start":"2026-03-09T01:41:52.726023Z","end":"2026-03-09T01:41:53.114166Z","steps":["trace[122856082] 'process raft request'  (duration: 387.969074ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:41:53.114846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:52.725993Z","time spent":"388.291081ms","remote":"127.0.0.1:43322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3297,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/replicasets/local-path-storage/local-path-provisioner-679c578f5\" mod_revision:546 > success:<request_put:<key:\"/registry/replicasets/local-path-storage/local-path-provisioner-679c578f5\" value_size:3216 >> failure:<request_range:<key:\"/registry/replicasets/local-path-storage/local-path-provisioner-679c578f5\" > >"}
{"level":"info","ts":"2026-03-09T01:42:42.361482Z","caller":"traceutil/trace.go:171","msg":"trace[550746384] linearizableReadLoop","detail":"{readStateIndex:1632; appliedIndex:1630; }","duration":"357.929671ms","start":"2026-03-09T01:42:42.003526Z","end":"2026-03-09T01:42:42.361456Z","steps":["trace[550746384] 'read index received'  (duration: 4.38262ms)","trace[550746384] 'applied index is now lower than readState.Index'  (duration: 353.546141ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:42:42.361712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.179967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"}
{"level":"info","ts":"2026-03-09T01:42:42.361792Z","caller":"traceutil/trace.go:171","msg":"trace[1669751572] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1581; }","duration":"358.30768ms","start":"2026-03-09T01:42:42.003461Z","end":"2026-03-09T01:42:42.361768Z","steps":["trace[1669751572] 'agreement among raft nodes before linearized reading'  (duration: 358.130466ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:42:42.361827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:42:42.003429Z","time spent":"358.392122ms","remote":"127.0.0.1:56512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "}
{"level":"warn","ts":"2026-03-09T01:42:42.36173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.733345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-09T01:42:42.361987Z","caller":"traceutil/trace.go:171","msg":"trace[1550786365] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1581; }","duration":"134.033992ms","start":"2026-03-09T01:42:42.227896Z","end":"2026-03-09T01:42:42.36193Z","steps":["trace[1550786365] 'agreement among raft nodes before linearized reading'  (duration: 133.744635ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:42:42.361774Z","caller":"traceutil/trace.go:171","msg":"trace[1125566501] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"407.635919ms","start":"2026-03-09T01:42:41.954019Z","end":"2026-03-09T01:42:42.361655Z","steps":["trace[1125566501] 'process raft request'  (duration: 407.25861ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:42:42.362159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:42:41.954001Z","time spent":"408.09552ms","remote":"127.0.0.1:43030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9334,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" mod_revision:1532 > success:<request_put:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" value_size:9277 >> failure:<request_range:<key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" > >"}
{"level":"info","ts":"2026-03-09T01:42:42.559283Z","caller":"traceutil/trace.go:171","msg":"trace[1679831423] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"189.221504ms","start":"2026-03-09T01:42:42.370031Z","end":"2026-03-09T01:42:42.559253Z","steps":["trace[1679831423] 'process raft request'  (duration: 98.131537ms)","trace[1679831423] 'compare'  (duration: 90.84411ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:42:42.559574Z","caller":"traceutil/trace.go:171","msg":"trace[1039407663] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"189.168903ms","start":"2026-03-09T01:42:42.370383Z","end":"2026-03-09T01:42:42.559552Z","steps":["trace[1039407663] 'process raft request'  (duration: 188.797134ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:42:42.55965Z","caller":"traceutil/trace.go:171","msg":"trace[983698117] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"189.125522ms","start":"2026-03-09T01:42:42.370492Z","end":"2026-03-09T01:42:42.559617Z","steps":["trace[983698117] 'process raft request'  (duration: 188.963398ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:43:26.230925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.816256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"}
{"level":"info","ts":"2026-03-09T01:43:26.231024Z","caller":"traceutil/trace.go:171","msg":"trace[31797932] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:1906; }","duration":"192.98542ms","start":"2026-03-09T01:43:26.038018Z","end":"2026-03-09T01:43:26.231004Z","steps":["trace[31797932] 'range keys from in-memory index tree'  (duration: 192.532819ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:43:26.396114Z","caller":"traceutil/trace.go:171","msg":"trace[1065068470] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"160.328612ms","start":"2026-03-09T01:43:26.235758Z","end":"2026-03-09T01:43:26.396087Z","steps":["trace[1065068470] 'process raft request'  (duration: 160.150438ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:43:26.396151Z","caller":"traceutil/trace.go:171","msg":"trace[1109000254] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1976; }","duration":"145.832848ms","start":"2026-03-09T01:43:26.250271Z","end":"2026-03-09T01:43:26.396103Z","steps":["trace[1109000254] 'read index received'  (duration: 145.798398ms)","trace[1109000254] 'applied index is now lower than readState.Index'  (duration: 10.73µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:43:26.396496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.192497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-03-09T01:43:26.396544Z","caller":"traceutil/trace.go:171","msg":"trace[769159594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1907; }","duration":"146.286859ms","start":"2026-03-09T01:43:26.250245Z","end":"2026-03-09T01:43:26.396532Z","steps":["trace[769159594] 'agreement among raft nodes before linearized reading'  (duration: 146.001203ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:43:26.39668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.725162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:43:26.396818Z","caller":"traceutil/trace.go:171","msg":"trace[678264868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1907; }","duration":"108.868465ms","start":"2026-03-09T01:43:26.287863Z","end":"2026-03-09T01:43:26.396731Z","steps":["trace[678264868] 'agreement among raft nodes before linearized reading'  (duration: 108.680831ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:43:26.56177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.835738ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7967603109031482014 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:1897 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:43:26.561964Z","caller":"traceutil/trace.go:171","msg":"trace[450636381] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"161.5563ms","start":"2026-03-09T01:43:26.400343Z","end":"2026-03-09T01:43:26.561899Z","steps":["trace[450636381] 'process raft request'  (duration: 55.432306ms)","trace[450636381] 'compare'  (duration: 105.712355ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:44:02.676215Z","caller":"traceutil/trace.go:171","msg":"trace[1721825850] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"205.593761ms","start":"2026-03-09T01:44:02.470595Z","end":"2026-03-09T01:44:02.676189Z","steps":["trace[1721825850] 'process raft request'  (duration: 204.827974ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:44:33.43069Z","caller":"traceutil/trace.go:171","msg":"trace[1717365927] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"110.105461ms","start":"2026-03-09T01:44:33.32055Z","end":"2026-03-09T01:44:33.430655Z","steps":["trace[1717365927] 'process raft request'  (duration: 109.866176ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:44:33.43181Z","caller":"traceutil/trace.go:171","msg":"trace[1472639542] transaction","detail":"{read_only:false; response_revision:2384; number_of_response:1; }","duration":"110.038369ms","start":"2026-03-09T01:44:33.321736Z","end":"2026-03-09T01:44:33.431774Z","steps":["trace[1472639542] 'process raft request'  (duration: 109.776794ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:44:33.431969Z","caller":"traceutil/trace.go:171","msg":"trace[1694441733] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"110.03228ms","start":"2026-03-09T01:44:33.321914Z","end":"2026-03-09T01:44:33.431946Z","steps":["trace[1694441733] 'process raft request'  (duration: 109.769573ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:44:38.704038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.626264ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7967603109031483441 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.204.45.78\" mod_revision:2355 > success:<request_put:<key:\"/registry/masterleases/199.204.45.78\" value_size:66 lease:7967603109031483439 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.78\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:44:38.704164Z","caller":"traceutil/trace.go:171","msg":"trace[61008308] linearizableReadLoop","detail":"{readStateIndex:2518; appliedIndex:2517; }","duration":"112.167608ms","start":"2026-03-09T01:44:38.591977Z","end":"2026-03-09T01:44:38.704144Z","steps":["trace[61008308] 'read index received'  (duration: 5.269337ms)","trace[61008308] 'applied index is now lower than readState.Index'  (duration: 106.897001ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:44:38.704206Z","caller":"traceutil/trace.go:171","msg":"trace[1490513193] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"146.003592ms","start":"2026-03-09T01:44:38.55817Z","end":"2026-03-09T01:44:38.704174Z","steps":["trace[1490513193] 'process raft request'  (duration: 39.133382ms)","trace[1490513193] 'compare'  (duration: 106.479571ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:44:38.704397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.420913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"}
{"level":"info","ts":"2026-03-09T01:44:38.704438Z","caller":"traceutil/trace.go:171","msg":"trace[1642721166] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2417; }","duration":"112.508635ms","start":"2026-03-09T01:44:38.591919Z","end":"2026-03-09T01:44:38.704428Z","steps":["trace[1642721166] 'agreement among raft nodes before linearized reading'  (duration: 112.323501ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:44:38.932432Z","caller":"traceutil/trace.go:171","msg":"trace[900173063] transaction","detail":"{read_only:false; response_revision:2419; number_of_response:1; }","duration":"200.836991ms","start":"2026-03-09T01:44:38.731565Z","end":"2026-03-09T01:44:38.932402Z","steps":["trace[900173063] 'process raft request'  (duration: 137.622483ms)","trace[900173063] 'compare'  (duration: 63.083775ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:44:38.978043Z","caller":"traceutil/trace.go:171","msg":"trace[217702631] transaction","detail":"{read_only:false; response_revision:2420; number_of_response:1; }","duration":"218.73721ms","start":"2026-03-09T01:44:38.759281Z","end":"2026-03-09T01:44:38.978018Z","steps":["trace[217702631] 'process raft request'  (duration: 218.537256ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:45:40.969929Z","caller":"traceutil/trace.go:171","msg":"trace[1738254150] linearizableReadLoop","detail":"{readStateIndex:3109; appliedIndex:3108; }","duration":"103.052143ms","start":"2026-03-09T01:45:40.866855Z","end":"2026-03-09T01:45:40.969907Z","steps":["trace[1738254150] 'read index received'  (duration: 102.89528ms)","trace[1738254150] 'applied index is now lower than readState.Index'  (duration: 155.843µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:45:40.970135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.274609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-09T01:45:40.970026Z","caller":"traceutil/trace.go:171","msg":"trace[1558320260] transaction","detail":"{read_only:false; response_revision:2979; number_of_response:1; }","duration":"146.45247ms","start":"2026-03-09T01:45:40.823525Z","end":"2026-03-09T01:45:40.969978Z","steps":["trace[1558320260] 'process raft request'  (duration: 146.244335ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:45:40.970277Z","caller":"traceutil/trace.go:171","msg":"trace[372172949] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2979; }","duration":"103.451572ms","start":"2026-03-09T01:45:40.866782Z","end":"2026-03-09T01:45:40.970233Z","steps":["trace[372172949] 'agreement among raft nodes before linearized reading'  (duration: 103.224826ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:46:48.30111Z","caller":"traceutil/trace.go:171","msg":"trace[2043225161] linearizableReadLoop","detail":"{readStateIndex:4092; appliedIndex:4090; }","duration":"107.790257ms","start":"2026-03-09T01:46:48.19328Z","end":"2026-03-09T01:46:48.30107Z","steps":["trace[2043225161] 'read index received'  (duration: 47.083267ms)","trace[2043225161] 'applied index is now lower than readState.Index'  (duration: 60.70513ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:46:48.301385Z","caller":"traceutil/trace.go:171","msg":"trace[1716914613] transaction","detail":"{read_only:false; response_revision:3918; number_of_response:1; }","duration":"107.221895ms","start":"2026-03-09T01:46:48.194109Z","end":"2026-03-09T01:46:48.301331Z","steps":["trace[1716914613] 'process raft request'  (duration: 106.885676ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:46:48.301533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.221646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cc88b86d6-gqz8b\" ","response":"range_response_count:1 size:5041"}
{"level":"info","ts":"2026-03-09T01:46:48.301629Z","caller":"traceutil/trace.go:171","msg":"trace[1803053993] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cc88b86d6-gqz8b; range_end:; response_count:1; response_revision:3918; }","duration":"108.36734ms","start":"2026-03-09T01:46:48.193238Z","end":"2026-03-09T01:46:48.301606Z","steps":["trace[1803053993] 'agreement among raft nodes before linearized reading'  (duration: 108.105634ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:46:48.302043Z","caller":"traceutil/trace.go:171","msg":"trace[1619650228] transaction","detail":"{read_only:false; response_revision:3917; number_of_response:1; }","duration":"109.610157ms","start":"2026-03-09T01:46:48.192334Z","end":"2026-03-09T01:46:48.301944Z","steps":["trace[1619650228] 'process raft request'  (duration: 86.401191ms)","trace[1619650228] 'compare'  (duration: 21.920497ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:46:48.337462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.213174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:71279"}
{"level":"info","ts":"2026-03-09T01:46:48.337565Z","caller":"traceutil/trace.go:171","msg":"trace[672884103] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:3919; }","duration":"143.352998ms","start":"2026-03-09T01:46:48.194191Z","end":"2026-03-09T01:46:48.337544Z","steps":["trace[672884103] 'agreement among raft nodes before linearized reading'  (duration: 142.958839ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:46:48.338152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.234929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:48 size:2349402"}
{"level":"info","ts":"2026-03-09T01:46:48.338213Z","caller":"traceutil/trace.go:171","msg":"trace[1779437504] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:48; response_revision:3919; }","duration":"100.346751ms","start":"2026-03-09T01:46:48.237851Z","end":"2026-03-09T01:46:48.338198Z","steps":["trace[1779437504] 'agreement among raft nodes before linearized reading'  (duration: 99.309268ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:46:52.736186Z","caller":"traceutil/trace.go:171","msg":"trace[412814561] transaction","detail":"{read_only:false; response_revision:4039; number_of_response:1; }","duration":"197.787987ms","start":"2026-03-09T01:46:52.538365Z","end":"2026-03-09T01:46:52.736153Z","steps":["trace[412814561] 'process raft request'  (duration: 197.494551ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:46:52.736197Z","caller":"traceutil/trace.go:171","msg":"trace[2038656051] transaction","detail":"{read_only:false; response_revision:4040; number_of_response:1; }","duration":"196.524849ms","start":"2026-03-09T01:46:52.539654Z","end":"2026-03-09T01:46:52.736178Z","steps":["trace[2038656051] 'process raft request'  (duration: 196.422717ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:46:52.736928Z","caller":"traceutil/trace.go:171","msg":"trace[833806570] linearizableReadLoop","detail":"{readStateIndex:4221; appliedIndex:4221; }","duration":"172.834092ms","start":"2026-03-09T01:46:52.564051Z","end":"2026-03-09T01:46:52.736885Z","steps":["trace[833806570] 'read index received'  (duration: 172.783131ms)","trace[833806570] 'applied index is now lower than readState.Index'  (duration: 15.67µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:46:52.737194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.113269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13485"}
{"level":"info","ts":"2026-03-09T01:46:52.737297Z","caller":"traceutil/trace.go:171","msg":"trace[54143291] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:4040; }","duration":"173.296203ms","start":"2026-03-09T01:46:52.563985Z","end":"2026-03-09T01:46:52.737281Z","steps":["trace[54143291] 'agreement among raft nodes before linearized reading'  (duration: 172.936845ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:46:52.759259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.897607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"}
{"level":"info","ts":"2026-03-09T01:46:52.759366Z","caller":"traceutil/trace.go:171","msg":"trace[1123240673] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4041; }","duration":"192.07946ms","start":"2026-03-09T01:46:52.567261Z","end":"2026-03-09T01:46:52.759341Z","steps":["trace[1123240673] 'agreement among raft nodes before linearized reading'  (duration: 191.761594ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:47:10.308992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.376688ms","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-03-09T01:47:10.309129Z","caller":"traceutil/trace.go:171","msg":"trace[1826451319] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4388; }","duration":"237.599643ms","start":"2026-03-09T01:47:10.071502Z","end":"2026-03-09T01:47:10.309101Z","steps":["trace[1826451319] 'range keys from in-memory index tree'  (duration: 237.092231ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:47:18.113062Z","caller":"traceutil/trace.go:171","msg":"trace[494125803] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"119.256571ms","start":"2026-03-09T01:47:17.993767Z","end":"2026-03-09T01:47:18.113023Z","steps":["trace[494125803] 'process raft request'  (duration: 73.466194ms)","trace[494125803] 'compare'  (duration: 45.368328ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:47:18.606835Z","caller":"traceutil/trace.go:171","msg":"trace[564076770] linearizableReadLoop","detail":"{readStateIndex:4722; appliedIndex:4721; }","duration":"154.555547ms","start":"2026-03-09T01:47:18.45224Z","end":"2026-03-09T01:47:18.606796Z","steps":["trace[564076770] 'read index received'  (duration: 154.315741ms)","trace[564076770] 'applied index is now lower than readState.Index'  (duration: 238.166µs)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:47:18.606896Z","caller":"traceutil/trace.go:171","msg":"trace[927800355] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"155.710443ms","start":"2026-03-09T01:47:18.45114Z","end":"2026-03-09T01:47:18.606851Z","steps":["trace[927800355] 'process raft request'  (duration: 155.441707ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:47:18.607219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.937905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"}
{"level":"info","ts":"2026-03-09T01:47:18.60735Z","caller":"traceutil/trace.go:171","msg":"trace[516260459] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4525; }","duration":"155.094248ms","start":"2026-03-09T01:47:18.452222Z","end":"2026-03-09T01:47:18.607316Z","steps":["trace[516260459] 'agreement among raft nodes before linearized reading'  (duration: 154.662738ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:47:18.615307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.133676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"}
{"level":"info","ts":"2026-03-09T01:47:18.615425Z","caller":"traceutil/trace.go:171","msg":"trace[211692022] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4526; }","duration":"158.267829ms","start":"2026-03-09T01:47:18.457133Z","end":"2026-03-09T01:47:18.615401Z","steps":["trace[211692022] 'agreement among raft nodes before linearized reading'  (duration: 157.908381ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:47:18.615459Z","caller":"traceutil/trace.go:171","msg":"trace[1038517114] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"157.958902ms","start":"2026-03-09T01:47:18.457471Z","end":"2026-03-09T01:47:18.615429Z","steps":["trace[1038517114] 'process raft request'  (duration: 157.182264ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:47:18.615587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.239397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"warn","ts":"2026-03-09T01:47:18.615613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.395301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-03-09T01:47:18.615658Z","caller":"traceutil/trace.go:171","msg":"trace[1327103734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4526; }","duration":"104.424831ms","start":"2026-03-09T01:47:18.511216Z","end":"2026-03-09T01:47:18.615641Z","steps":["trace[1327103734] 'agreement among raft nodes before linearized reading'  (duration: 104.022332ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:47:18.615665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.035014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-03-09T01:47:18.615778Z","caller":"traceutil/trace.go:171","msg":"trace[1753399854] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:4526; }","duration":"131.183956ms","start":"2026-03-09T01:47:18.484571Z","end":"2026-03-09T01:47:18.615755Z","steps":["trace[1753399854] 'agreement among raft nodes before linearized reading'  (duration: 130.89179ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:47:18.615878Z","caller":"traceutil/trace.go:171","msg":"trace[234766644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4526; }","duration":"104.542014ms","start":"2026-03-09T01:47:18.511136Z","end":"2026-03-09T01:47:18.615678Z","steps":["trace[234766644] 'agreement among raft nodes before linearized reading'  (duration: 104.222377ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:50:55.1687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3072}
{"level":"info","ts":"2026-03-09T01:50:55.257108Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3072,"took":"86.636766ms","hash":2735027662,"current-db-size-bytes":43896832,"current-db-size":"44 MB","current-db-size-in-use-bytes":30461952,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-03-09T01:50:55.257193Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2735027662,"revision":3072,"compact-revision":-1}
{"level":"info","ts":"2026-03-09T01:53:20.678091Z","caller":"traceutil/trace.go:171","msg":"trace[686331711] transaction","detail":"{read_only:false; response_revision:7677; number_of_response:1; }","duration":"202.919207ms","start":"2026-03-09T01:53:20.47513Z","end":"2026-03-09T01:53:20.678049Z","steps":["trace[686331711] 'process raft request'  (duration: 202.582989ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:53:20.857023Z","caller":"traceutil/trace.go:171","msg":"trace[1280666194] linearizableReadLoop","detail":"{readStateIndex:8036; appliedIndex:8035; }","duration":"167.232066ms","start":"2026-03-09T01:53:20.689752Z","end":"2026-03-09T01:53:20.856984Z","steps":["trace[1280666194] 'read index received'  (duration: 108.879194ms)","trace[1280666194] 'applied index is now lower than readState.Index'  (duration: 58.349861ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:53:20.857086Z","caller":"traceutil/trace.go:171","msg":"trace[1715958893] transaction","detail":"{read_only:false; response_revision:7678; number_of_response:1; }","duration":"173.600647ms","start":"2026-03-09T01:53:20.68345Z","end":"2026-03-09T01:53:20.857051Z","steps":["trace[1715958893] 'process raft request'  (duration: 115.191014ms)","trace[1715958893] 'compare'  (duration: 58.064465ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:53:20.857287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.533843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:53:20.857344Z","caller":"traceutil/trace.go:171","msg":"trace[1142591661] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:7678; }","duration":"167.624005ms","start":"2026-03-09T01:53:20.689703Z","end":"2026-03-09T01:53:20.857327Z","steps":["trace[1142591661] 'agreement among raft nodes before linearized reading'  (duration: 167.489741ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:53:31.65633Z","caller":"traceutil/trace.go:171","msg":"trace[1033724549] transaction","detail":"{read_only:false; response_revision:7746; number_of_response:1; }","duration":"124.219912ms","start":"2026-03-09T01:53:31.53208Z","end":"2026-03-09T01:53:31.6563Z","steps":["trace[1033724549] 'process raft request'  (duration: 124.020878ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:53:32.917282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.401819ms","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-03-09T01:53:32.917389Z","caller":"traceutil/trace.go:171","msg":"trace[150334234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7750; }","duration":"176.574532ms","start":"2026-03-09T01:53:32.740793Z","end":"2026-03-09T01:53:32.917367Z","steps":["trace[150334234] 'range keys from in-memory index tree'  (duration: 176.118211ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:53:33.879904Z","caller":"traceutil/trace.go:171","msg":"trace[246878011] linearizableReadLoop","detail":"{readStateIndex:8120; appliedIndex:8119; }","duration":"230.913926ms","start":"2026-03-09T01:53:33.64896Z","end":"2026-03-09T01:53:33.879874Z","steps":["trace[246878011] 'read index received'  (duration: 203.825586ms)","trace[246878011] 'applied index is now lower than readState.Index'  (duration: 27.08643ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:53:33.880472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.470988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"}
{"level":"info","ts":"2026-03-09T01:53:33.880535Z","caller":"traceutil/trace.go:171","msg":"trace[1226261257] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7756; }","duration":"231.622042ms","start":"2026-03-09T01:53:33.648902Z","end":"2026-03-09T01:53:33.880524Z","steps":["trace[1226261257] 'agreement among raft nodes before linearized reading'  (duration: 231.373176ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:53:33.880481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.961405ms","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":"warn","ts":"2026-03-09T01:53:33.880702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.301332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drzc4\" ","response":"range_response_count:1 size:6520"}
{"level":"info","ts":"2026-03-09T01:53:33.880805Z","caller":"traceutil/trace.go:171","msg":"trace[1132681435] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drzc4; range_end:; response_count:1; response_revision:7756; }","duration":"197.473095ms","start":"2026-03-09T01:53:33.683309Z","end":"2026-03-09T01:53:33.880782Z","steps":["trace[1132681435] 'agreement among raft nodes before linearized reading'  (duration: 197.122658ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:53:33.880705Z","caller":"traceutil/trace.go:171","msg":"trace[649060826] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7756; }","duration":"207.187551ms","start":"2026-03-09T01:53:33.673477Z","end":"2026-03-09T01:53:33.880665Z","steps":["trace[649060826] 'agreement among raft nodes before linearized reading'  (duration: 206.925045ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:53:59.96396Z","caller":"traceutil/trace.go:171","msg":"trace[1038969517] linearizableReadLoop","detail":"{readStateIndex:8348; appliedIndex:8347; }","duration":"117.457303ms","start":"2026-03-09T01:53:59.846474Z","end":"2026-03-09T01:53:59.963931Z","steps":["trace[1038969517] 'read index received'  (duration: 117.190067ms)","trace[1038969517] 'applied index is now lower than readState.Index'  (duration: 265.806µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:53:59.964141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.634707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-09T01:53:59.964185Z","caller":"traceutil/trace.go:171","msg":"trace[1550142671] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:7973; }","duration":"117.74815ms","start":"2026-03-09T01:53:59.846428Z","end":"2026-03-09T01:53:59.964176Z","steps":["trace[1550142671] 'agreement among raft nodes before linearized reading'  (duration: 117.621357ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:53:59.964541Z","caller":"traceutil/trace.go:171","msg":"trace[1872420088] transaction","detail":"{read_only:false; response_revision:7973; number_of_response:1; }","duration":"122.30497ms","start":"2026-03-09T01:53:59.842218Z","end":"2026-03-09T01:53:59.964523Z","steps":["trace[1872420088] 'process raft request'  (duration: 121.450161ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:01.521875Z","caller":"traceutil/trace.go:171","msg":"trace[1100309858] transaction","detail":"{read_only:false; response_revision:7979; number_of_response:1; }","duration":"375.304456ms","start":"2026-03-09T01:54:01.146553Z","end":"2026-03-09T01:54:01.521857Z","steps":["trace[1100309858] 'process raft request'  (duration: 375.04546ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:01.522052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:01.146522Z","time spent":"375.44027ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:7967 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-09T01:54:01.521777Z","caller":"traceutil/trace.go:171","msg":"trace[1425586196] linearizableReadLoop","detail":"{readStateIndex:8355; appliedIndex:8354; }","duration":"366.665515ms","start":"2026-03-09T01:54:01.155088Z","end":"2026-03-09T01:54:01.521753Z","steps":["trace[1425586196] 'read index received'  (duration: 366.425919ms)","trace[1425586196] 'applied index is now lower than readState.Index'  (duration: 238.126µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:54:01.52219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.078934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-03-09T01:54:01.522284Z","caller":"traceutil/trace.go:171","msg":"trace[828923622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7979; }","duration":"367.232838ms","start":"2026-03-09T01:54:01.15503Z","end":"2026-03-09T01:54:01.522263Z","steps":["trace[828923622] 'agreement among raft nodes before linearized reading'  (duration: 366.951632ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:01.522332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:01.155006Z","time spent":"367.317789ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-03-09T01:54:01.522383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.798366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-03-09T01:54:01.52242Z","caller":"traceutil/trace.go:171","msg":"trace[1655290406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7979; }","duration":"240.851757ms","start":"2026-03-09T01:54:01.281556Z","end":"2026-03-09T01:54:01.522408Z","steps":["trace[1655290406] 'agreement among raft nodes before linearized reading'  (duration: 240.750255ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:01.522555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.850018ms","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-03-09T01:54:01.522593Z","caller":"traceutil/trace.go:171","msg":"trace[1827360576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7979; }","duration":"296.924479ms","start":"2026-03-09T01:54:01.225648Z","end":"2026-03-09T01:54:01.522572Z","steps":["trace[1827360576] 'agreement among raft nodes before linearized reading'  (duration: 296.587312ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:01.779697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.964746ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7967603109031498365 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:7970 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:54:01.779924Z","caller":"traceutil/trace.go:171","msg":"trace[2003569934] transaction","detail":"{read_only:false; response_revision:7980; number_of_response:1; }","duration":"252.622649ms","start":"2026-03-09T01:54:01.527267Z","end":"2026-03-09T01:54:01.77989Z","steps":["trace[2003569934] 'process raft request'  (duration: 50.346795ms)","trace[2003569934] 'compare'  (duration: 201.828073ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:54:01.779987Z","caller":"traceutil/trace.go:171","msg":"trace[491662798] transaction","detail":"{read_only:false; response_revision:7983; number_of_response:1; }","duration":"249.155151ms","start":"2026-03-09T01:54:01.530811Z","end":"2026-03-09T01:54:01.779966Z","steps":["trace[491662798] 'process raft request'  (duration: 249.10765ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:01.780205Z","caller":"traceutil/trace.go:171","msg":"trace[126007008] transaction","detail":"{read_only:false; response_revision:7981; number_of_response:1; }","duration":"252.323141ms","start":"2026-03-09T01:54:01.527862Z","end":"2026-03-09T01:54:01.780185Z","steps":["trace[126007008] 'process raft request'  (duration: 251.954963ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:01.78029Z","caller":"traceutil/trace.go:171","msg":"trace[1178149754] transaction","detail":"{read_only:false; response_revision:7982; number_of_response:1; }","duration":"252.436274ms","start":"2026-03-09T01:54:01.527844Z","end":"2026-03-09T01:54:01.780281Z","steps":["trace[1178149754] 'process raft request'  (duration: 252.035325ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:14.431985Z","caller":"traceutil/trace.go:171","msg":"trace[1237848829] transaction","detail":"{read_only:false; response_revision:8101; number_of_response:1; }","duration":"101.780969ms","start":"2026-03-09T01:54:14.330169Z","end":"2026-03-09T01:54:14.43195Z","steps":["trace[1237848829] 'process raft request'  (duration: 101.591795ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:14.588121Z","caller":"traceutil/trace.go:171","msg":"trace[1714565087] linearizableReadLoop","detail":"{readStateIndex:8487; appliedIndex:8486; }","duration":"112.108048ms","start":"2026-03-09T01:54:14.475978Z","end":"2026-03-09T01:54:14.588086Z","steps":["trace[1714565087] 'read index received'  (duration: 84.905084ms)","trace[1714565087] 'applied index is now lower than readState.Index'  (duration: 27.201424ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:54:14.588145Z","caller":"traceutil/trace.go:171","msg":"trace[2097602689] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"122.859298ms","start":"2026-03-09T01:54:14.465242Z","end":"2026-03-09T01:54:14.588101Z","steps":["trace[2097602689] 'process raft request'  (duration: 95.697554ms)","trace[2097602689] 'compare'  (duration: 26.99426ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:54:14.588388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.397345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"}
{"level":"info","ts":"2026-03-09T01:54:14.588434Z","caller":"traceutil/trace.go:171","msg":"trace[1838205999] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8103; }","duration":"112.515768ms","start":"2026-03-09T01:54:14.475904Z","end":"2026-03-09T01:54:14.58842Z","steps":["trace[1838205999] 'agreement among raft nodes before linearized reading'  (duration: 112.313633ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:15.355558Z","caller":"traceutil/trace.go:171","msg":"trace[483051356] transaction","detail":"{read_only:false; response_revision:8107; number_of_response:1; }","duration":"361.966317ms","start":"2026-03-09T01:54:14.993563Z","end":"2026-03-09T01:54:15.355529Z","steps":["trace[483051356] 'process raft request'  (duration: 361.755732ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:15.35574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:14.993533Z","time spent":"362.113989ms","remote":"127.0.0.1:43030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4596,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/rook-ceph-detect-version-ck88b\" mod_revision:8101 > success:<request_put:<key:\"/registry/pods/openstack/rook-ceph-detect-version-ck88b\" value_size:4533 >> failure:<request_range:<key:\"/registry/pods/openstack/rook-ceph-detect-version-ck88b\" > >"}
{"level":"info","ts":"2026-03-09T01:54:15.484833Z","caller":"traceutil/trace.go:171","msg":"trace[1133695503] linearizableReadLoop","detail":"{readStateIndex:8492; appliedIndex:8491; }","duration":"129.28302ms","start":"2026-03-09T01:54:15.355499Z","end":"2026-03-09T01:54:15.484783Z","steps":["trace[1133695503] 'read index received'  (duration: 90.039299ms)","trace[1133695503] 'applied index is now lower than readState.Index'  (duration: 39.242201ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:54:15.484923Z","caller":"traceutil/trace.go:171","msg":"trace[969025218] transaction","detail":"{read_only:false; response_revision:8108; number_of_response:1; }","duration":"192.885353ms","start":"2026-03-09T01:54:15.291966Z","end":"2026-03-09T01:54:15.484851Z","steps":["trace[969025218] 'process raft request'  (duration: 153.618731ms)","trace[969025218] 'compare'  (duration: 38.918104ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:54:15.485019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.180963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:54:15.485135Z","caller":"traceutil/trace.go:171","msg":"trace[1421705771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8108; }","duration":"195.337107ms","start":"2026-03-09T01:54:15.289764Z","end":"2026-03-09T01:54:15.485101Z","steps":["trace[1421705771] 'agreement among raft nodes before linearized reading'  (duration: 195.146573ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:15.485483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.214505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"}
{"level":"info","ts":"2026-03-09T01:54:15.485586Z","caller":"traceutil/trace.go:171","msg":"trace[1700784853] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8108; }","duration":"123.385669ms","start":"2026-03-09T01:54:15.362183Z","end":"2026-03-09T01:54:15.485569Z","steps":["trace[1700784853] 'agreement among raft nodes before linearized reading'  (duration: 122.990951ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:15.963585Z","caller":"traceutil/trace.go:171","msg":"trace[1158677855] transaction","detail":"{read_only:false; response_revision:8112; number_of_response:1; }","duration":"104.05775ms","start":"2026-03-09T01:54:15.859503Z","end":"2026-03-09T01:54:15.96356Z","steps":["trace[1158677855] 'process raft request'  (duration: 103.902537ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:16.402166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.516685ms","expected-duration":"100ms","prefix":"","request":"header:<ID:7967603109031498755 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:8086 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:54:16.402327Z","caller":"traceutil/trace.go:171","msg":"trace[1201709281] linearizableReadLoop","detail":"{readStateIndex:8498; appliedIndex:8496; }","duration":"526.887937ms","start":"2026-03-09T01:54:15.875412Z","end":"2026-03-09T01:54:16.4023Z","steps":["trace[1201709281] 'read index received'  (duration: 88.111906ms)","trace[1201709281] 'applied index is now lower than readState.Index'  (duration: 438.772481ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:54:16.402381Z","caller":"traceutil/trace.go:171","msg":"trace[1037511355] transaction","detail":"{read_only:false; response_revision:8113; number_of_response:1; }","duration":"540.99704ms","start":"2026-03-09T01:54:15.861327Z","end":"2026-03-09T01:54:16.402324Z","steps":["trace[1037511355] 'process raft request'  (duration: 288.123717ms)","trace[1037511355] 'compare'  (duration: 251.844761ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:54:16.402981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:15.861317Z","time spent":"541.419269ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:8086 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-03-09T01:54:16.403001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.518854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-03-09T01:54:16.403031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.38211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:54:16.403104Z","caller":"traceutil/trace.go:171","msg":"trace[1515121500] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8113; }","duration":"169.648157ms","start":"2026-03-09T01:54:16.233431Z","end":"2026-03-09T01:54:16.403079Z","steps":["trace[1515121500] 'agreement among raft nodes before linearized reading'  (duration: 169.462793ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:16.403121Z","caller":"traceutil/trace.go:171","msg":"trace[1129891317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8113; }","duration":"114.529313ms","start":"2026-03-09T01:54:16.288568Z","end":"2026-03-09T01:54:16.403098Z","steps":["trace[1129891317] 'agreement among raft nodes before linearized reading'  (duration: 114.36278ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:16.403247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.597052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-drzc4\" ","response":"range_response_count:1 size:6520"}
{"level":"warn","ts":"2026-03-09T01:54:16.403354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.917251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-03-09T01:54:16.403361Z","caller":"traceutil/trace.go:171","msg":"trace[566144541] range","detail":"{range_begin:/registry/pods/openstack/keepalived-drzc4; range_end:; response_count:1; response_revision:8113; }","duration":"218.763726ms","start":"2026-03-09T01:54:16.184569Z","end":"2026-03-09T01:54:16.403332Z","steps":["trace[566144541] 'agreement among raft nodes before linearized reading'  (duration: 218.383888ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:16.403336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.938621ms","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-03-09T01:54:16.403448Z","caller":"traceutil/trace.go:171","msg":"trace[82180288] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8113; }","duration":"492.089605ms","start":"2026-03-09T01:54:15.911331Z","end":"2026-03-09T01:54:16.403421Z","steps":["trace[82180288] 'agreement among raft nodes before linearized reading'  (duration: 491.720757ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:54:16.403454Z","caller":"traceutil/trace.go:171","msg":"trace[1584390000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8113; }","duration":"528.107284ms","start":"2026-03-09T01:54:15.875322Z","end":"2026-03-09T01:54:16.403429Z","steps":["trace[1584390000] 'agreement among raft nodes before linearized reading'  (duration: 527.110632ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:54:16.403497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:15.911319Z","time spent":"492.168687ms","remote":"127.0.0.1:42944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-03-09T01:54:16.403536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:15.875274Z","time spent":"528.248897ms","remote":"127.0.0.1:43094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-03-09T01:55:55.176766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6309}
{"level":"info","ts":"2026-03-09T01:55:55.285418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6309,"took":"106.572376ms","hash":1944389325,"current-db-size-bytes":47394816,"current-db-size":"47 MB","current-db-size-in-use-bytes":23601152,"current-db-size-in-use":"24 MB"}
{"level":"info","ts":"2026-03-09T01:55:55.285522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1944389325,"revision":6309,"compact-revision":3072}
{"level":"info","ts":"2026-03-09T01:56:48.638449Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-03-09T01:56:48.645195Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-03-09T01:56:48.645316Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-03-09T02:00:55.183068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9058}
{"level":"info","ts":"2026-03-09T02:00:55.273796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9058,"took":"88.316695ms","hash":613475439,"current-db-size-bytes":47394816,"current-db-size":"47 MB","current-db-size-in-use-bytes":21999616,"current-db-size-in-use":"22 MB"}
{"level":"info","ts":"2026-03-09T02:00:55.273872Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":613475439,"revision":9058,"compact-revision":6309}