{"level":"warn","ts":"2026-08-05T21:41:46.38408Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T21:41:46.384199Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243: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.19.213.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243: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-08-05T21:41:46.384265Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T21:41:46.384274Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-08-05T21:41:46.384294Z","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-08-05T21:41:46.384874Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-08-05T21:41:46.384954Z","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.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243: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-08-05T21:41:46.387917Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.708212ms"} {"level":"info","ts":"2026-08-05T21:41:46.392625Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-08-05T21:41:46.392705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T21:41:46.392733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-08-05T21:41:46.392752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T21:41:46.392762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-08-05T21:41:46.392809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-08-05T21:41:46.395904Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T21:41:46.397688Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T21:41:46.398918Z","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-08-05T21:41:46.400885Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T21:41:46.401373Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T21:41:46.401567Z","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-08-05T21:41:46.401681Z","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-08-05T21:41:46.401698Z","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-08-05T21:41:46.404188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-08-05T21:41:46.404341Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-08-05T21:41:46.405293Z","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-08-05T21:41:46.405389Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-08-05T21:41:46.405498Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-08-05T21:41:46.405591Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T21:41:46.405632Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T21:41:46.693395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T21:41:46.693452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T21:41:46.693482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-08-05T21:41:46.693496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-08-05T21:41:46.693507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-08-05T21:41:46.693517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-08-05T21:41:46.693526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-08-05T21:41:46.694322Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T21:41:46.694328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:41:46.694385Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:41:46.694408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:41:46.694699Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T21:41:46.694776Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T21:41:46.695396Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:41:46.695492Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:41:46.695527Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:41:46.69691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-08-05T21:41:46.697541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-05T21:42:02.789389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.147057ms","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-08-05T21:42:02.789536Z","caller":"traceutil/trace.go:171","msg":"trace[1571923491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"229.363331ms","start":"2026-08-05T21:42:02.560147Z","end":"2026-08-05T21:42:02.78951Z","steps":["trace[1571923491] 'range keys from in-memory index tree' (duration: 229.013934ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:33.542439Z","caller":"traceutil/trace.go:171","msg":"trace[1568365755] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"299.958178ms","start":"2026-08-05T21:42:33.242451Z","end":"2026-08-05T21:42:33.542409Z","steps":["trace[1568365755] 'process raft request' (duration: 299.776603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:33.54304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:33.242421Z","time spent":"300.218772ms","remote":"127.0.0.1:45670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:42:33.840781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.504316ms","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":"info","ts":"2026-08-05T21:42:33.841011Z","caller":"traceutil/trace.go:171","msg":"trace[163605370] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:605; }","duration":"283.824442ms","start":"2026-08-05T21:42:33.55716Z","end":"2026-08-05T21:42:33.840985Z","steps":["trace[163605370] 'range keys from in-memory index tree' (duration: 283.348052ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:48.872442Z","caller":"traceutil/trace.go:171","msg":"trace[1514900721] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"169.464573ms","start":"2026-08-05T21:42:48.702944Z","end":"2026-08-05T21:42:48.872409Z","steps":["trace[1514900721] 'process raft request' (duration: 169.140607ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:48.875223Z","caller":"traceutil/trace.go:171","msg":"trace[850152093] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"171.468212ms","start":"2026-08-05T21:42:48.70374Z","end":"2026-08-05T21:42:48.875208Z","steps":["trace[850152093] 'process raft request' (duration: 171.35371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:43:41.751284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.912278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:43:41.751442Z","caller":"traceutil/trace.go:171","msg":"trace[1941239196] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"159.229243ms","start":"2026-08-05T21:43:41.592191Z","end":"2026-08-05T21:43:41.75142Z","steps":["trace[1941239196] 'process raft request' (duration: 39.836776ms)","trace[1941239196] 'compare' (duration: 118.742945ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:05.490715Z","caller":"traceutil/trace.go:171","msg":"trace[1093946299] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"162.943114ms","start":"2026-08-05T21:44:05.327737Z","end":"2026-08-05T21:44:05.490681Z","steps":["trace[1093946299] 'process raft request' (duration: 162.75694ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:05.50047Z","caller":"traceutil/trace.go:171","msg":"trace[67938235] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"167.884418ms","start":"2026-08-05T21:44:05.332548Z","end":"2026-08-05T21:44:05.500433Z","steps":["trace[67938235] 'process raft request' (duration: 167.718995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.694864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.053881ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-05T21:44:05.69528Z","caller":"traceutil/trace.go:171","msg":"trace[1187985337] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1910; }","duration":"106.75473ms","start":"2026-08-05T21:44:05.588501Z","end":"2026-08-05T21:44:05.695255Z","steps":["trace[1187985337] 'read index received' (duration: 3.101809ms)","trace[1187985337] 'applied index is now lower than readState.Index' (duration: 103.650231ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:05.695365Z","caller":"traceutil/trace.go:171","msg":"trace[475972484] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"108.328249ms","start":"2026-08-05T21:44:05.587027Z","end":"2026-08-05T21:44:05.695355Z","steps":["trace[475972484] 'process raft request' (duration: 108.010743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.695731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.178818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e79d5ef1-4ccf-44ae-bead-75f2537fd201\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-05T21:44:05.695833Z","caller":"traceutil/trace.go:171","msg":"trace[2071019603] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e79d5ef1-4ccf-44ae-bead-75f2537fd201; range_end:; response_count:1; response_revision:1835; }","duration":"107.338271ms","start":"2026-08-05T21:44:05.588472Z","end":"2026-08-05T21:44:05.69581Z","steps":["trace[2071019603] 'agreement among raft nodes before linearized reading' (duration: 106.850302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:05.943586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.65883ms","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-08-05T21:44:05.943777Z","caller":"traceutil/trace.go:171","msg":"trace[223401254] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1836; }","duration":"126.922955ms","start":"2026-08-05T21:44:05.816825Z","end":"2026-08-05T21:44:05.943748Z","steps":["trace[223401254] 'range keys from in-memory index tree' (duration: 126.407585ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:15.18235Z","caller":"traceutil/trace.go:171","msg":"trace[761700706] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"141.662538ms","start":"2026-08-05T21:44:15.040656Z","end":"2026-08-05T21:44:15.182319Z","steps":["trace[761700706] 'process raft request' (duration: 141.461284ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:19.696767Z","caller":"traceutil/trace.go:171","msg":"trace[235308308] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"118.548715ms","start":"2026-08-05T21:44:19.578182Z","end":"2026-08-05T21:44:19.696731Z","steps":["trace[235308308] 'process raft request' (duration: 118.300551ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:19.710931Z","caller":"traceutil/trace.go:171","msg":"trace[237740053] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2028; }","duration":"103.60844ms","start":"2026-08-05T21:44:19.60729Z","end":"2026-08-05T21:44:19.710899Z","steps":["trace[237740053] 'read index received' (duration: 89.167734ms)","trace[237740053] 'applied index is now lower than readState.Index' (duration: 14.439186ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:44:19.711246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.933727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T21:44:19.711297Z","caller":"traceutil/trace.go:171","msg":"trace[1313125509] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1950; }","duration":"104.049059ms","start":"2026-08-05T21:44:19.607231Z","end":"2026-08-05T21:44:19.71128Z","steps":["trace[1313125509] 'agreement among raft nodes before linearized reading' (duration: 103.792993ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:19.711308Z","caller":"traceutil/trace.go:171","msg":"trace[1856668] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"132.579044ms","start":"2026-08-05T21:44:19.578698Z","end":"2026-08-05T21:44:19.711277Z","steps":["trace[1856668] 'process raft request' (duration: 131.965592ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:52.032118Z","caller":"traceutil/trace.go:171","msg":"trace[1793261238] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"157.507873ms","start":"2026-08-05T21:44:51.874584Z","end":"2026-08-05T21:44:52.032092Z","steps":["trace[1793261238] 'process raft request' (duration: 157.356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:52.22173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.866135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:44:52.22199Z","caller":"traceutil/trace.go:171","msg":"trace[1952006358] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"347.263552ms","start":"2026-08-05T21:44:51.874692Z","end":"2026-08-05T21:44:52.221955Z","steps":["trace[1952006358] 'process raft request' (duration: 246.02606ms)","trace[1952006358] 'compare' (duration: 100.661842ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:44:52.222173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:51.874644Z","time spent":"347.429595ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:44:52.222314Z","caller":"traceutil/trace.go:171","msg":"trace[1417293051] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"344.841517ms","start":"2026-08-05T21:44:51.877464Z","end":"2026-08-05T21:44:52.222305Z","steps":["trace[1417293051] 'process raft request' (duration: 344.44514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:52.222366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:51.877452Z","time spent":"344.886988ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:44:52.22258Z","caller":"traceutil/trace.go:171","msg":"trace[111148089] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2224; }","duration":"287.206221ms","start":"2026-08-05T21:44:51.935279Z","end":"2026-08-05T21:44:52.222485Z","steps":["trace[111148089] 'read index received' (duration: 96.693098ms)","trace[111148089] 'applied index is now lower than readState.Index' (duration: 190.505263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:44:52.222694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.403084ms","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-08-05T21:44:52.222755Z","caller":"traceutil/trace.go:171","msg":"trace[1117387294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2141; }","duration":"287.468025ms","start":"2026-08-05T21:44:51.935271Z","end":"2026-08-05T21:44:52.222739Z","steps":["trace[1117387294] 'agreement among raft nodes before linearized reading' (duration: 287.273612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:52.222969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.946103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-05T21:44:52.223035Z","caller":"traceutil/trace.go:171","msg":"trace[257647898] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"263.090725ms","start":"2026-08-05T21:44:51.959924Z","end":"2026-08-05T21:44:52.223014Z","steps":["trace[257647898] 'agreement among raft nodes before linearized reading' (duration: 262.829571ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:57.482737Z","caller":"traceutil/trace.go:171","msg":"trace[1162017040] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"130.8393ms","start":"2026-08-05T21:44:57.351873Z","end":"2026-08-05T21:44:57.482712Z","steps":["trace[1162017040] 'process raft request' (duration: 130.642116ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:18.86632Z","caller":"traceutil/trace.go:171","msg":"trace[663029535] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"121.146107ms","start":"2026-08-05T21:46:18.745153Z","end":"2026-08-05T21:46:18.866299Z","steps":["trace[663029535] 'process raft request' (duration: 121.008515ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:18.8893Z","caller":"traceutil/trace.go:171","msg":"trace[2077165242] linearizableReadLoop","detail":"{readStateIndex:2917; appliedIndex:2915; }","duration":"123.329339ms","start":"2026-08-05T21:46:18.76594Z","end":"2026-08-05T21:46:18.889269Z","steps":["trace[2077165242] 'read index received' (duration: 100.216948ms)","trace[2077165242] 'applied index is now lower than readState.Index' (duration: 23.110931ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:46:18.889331Z","caller":"traceutil/trace.go:171","msg":"trace[1367895333] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"143.988874ms","start":"2026-08-05T21:46:18.745311Z","end":"2026-08-05T21:46:18.8893Z","steps":["trace[1367895333] 'process raft request' (duration: 143.723519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:18.889684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.688077ms","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-08-05T21:46:18.889792Z","caller":"traceutil/trace.go:171","msg":"trace[1273193028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2805; }","duration":"123.87979ms","start":"2026-08-05T21:46:18.765892Z","end":"2026-08-05T21:46:18.889771Z","steps":["trace[1273193028] 'agreement among raft nodes before linearized reading' (duration: 123.467731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:46:18.889721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.878961ms","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-08-05T21:46:18.889971Z","caller":"traceutil/trace.go:171","msg":"trace[1014029088] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2805; }","duration":"123.152026ms","start":"2026-08-05T21:46:18.766778Z","end":"2026-08-05T21:46:18.88993Z","steps":["trace[1014029088] 'agreement among raft nodes before linearized reading' (duration: 122.733968ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:48:57.616179Z","caller":"traceutil/trace.go:171","msg":"trace[1565117394] linearizableReadLoop","detail":"{readStateIndex:3990; appliedIndex:3989; }","duration":"277.070609ms","start":"2026-08-05T21:48:57.339077Z","end":"2026-08-05T21:48:57.616147Z","steps":["trace[1565117394] 'read index received' (duration: 276.918527ms)","trace[1565117394] 'applied index is now lower than readState.Index' (duration: 150.582µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:48:57.616217Z","caller":"traceutil/trace.go:171","msg":"trace[1048698389] transaction","detail":"{read_only:false; response_revision:3839; number_of_response:1; }","duration":"342.304148ms","start":"2026-08-05T21:48:57.27389Z","end":"2026-08-05T21:48:57.616194Z","steps":["trace[1048698389] 'process raft request' (duration: 342.084734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:48:57.616389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.751548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T21:48:57.616389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:48:57.273864Z","time spent":"342.42535ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:48:57.616447Z","caller":"traceutil/trace.go:171","msg":"trace[1998699791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3839; }","duration":"150.809619ms","start":"2026-08-05T21:48:57.465624Z","end":"2026-08-05T21:48:57.616433Z","steps":["trace[1998699791] 'agreement among raft nodes before linearized reading' (duration: 150.706847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:48:57.616541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.429676ms","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-08-05T21:48:57.616833Z","caller":"traceutil/trace.go:171","msg":"trace[737513135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3839; }","duration":"277.783403ms","start":"2026-08-05T21:48:57.339029Z","end":"2026-08-05T21:48:57.616812Z","steps":["trace[737513135] 'agreement among raft nodes before linearized reading' (duration: 277.260313ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:49:00.135453Z","caller":"traceutil/trace.go:171","msg":"trace[2008431484] transaction","detail":"{read_only:false; response_revision:3855; number_of_response:1; }","duration":"104.624725ms","start":"2026-08-05T21:49:00.030797Z","end":"2026-08-05T21:49:00.135421Z","steps":["trace[2008431484] 'process raft request' (duration: 40.444975ms)","trace[2008431484] 'compare' (duration: 64.062158ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:50:18.200544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.647914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T21:50:18.200651Z","caller":"traceutil/trace.go:171","msg":"trace[844394694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4803; }","duration":"170.811457ms","start":"2026-08-05T21:50:18.029819Z","end":"2026-08-05T21:50:18.20063Z","steps":["trace[844394694] 'range keys from in-memory index tree' (duration: 170.44857ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:18.229479Z","caller":"traceutil/trace.go:171","msg":"trace[224594493] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"151.077166ms","start":"2026-08-05T21:50:18.078372Z","end":"2026-08-05T21:50:18.22945Z","steps":["trace[224594493] 'process raft request' (duration: 150.863132ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:18.240551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.846907ms","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-08-05T21:50:18.240632Z","caller":"traceutil/trace.go:171","msg":"trace[2027312688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4805; }","duration":"100.946839ms","start":"2026-08-05T21:50:18.139668Z","end":"2026-08-05T21:50:18.240614Z","steps":["trace[2027312688] 'agreement among raft nodes before linearized reading' (duration: 100.689444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:18.240833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.348828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T21:50:18.240904Z","caller":"traceutil/trace.go:171","msg":"trace[1809159101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4805; }","duration":"100.433479ms","start":"2026-08-05T21:50:18.140449Z","end":"2026-08-05T21:50:18.240882Z","steps":["trace[1809159101] 'agreement among raft nodes before linearized reading' (duration: 100.239155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:23.075931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.572346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:50:23.076175Z","caller":"traceutil/trace.go:171","msg":"trace[267776284] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5198; }","duration":"205.901845ms","start":"2026-08-05T21:50:22.870255Z","end":"2026-08-05T21:50:23.076156Z","steps":["trace[267776284] 'read index received' (duration: 27.091314ms)","trace[267776284] 'applied index is now lower than readState.Index' (duration: 178.809301ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:50:23.076184Z","caller":"traceutil/trace.go:171","msg":"trace[745081] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"235.455275ms","start":"2026-08-05T21:50:22.840685Z","end":"2026-08-05T21:50:23.07614Z","steps":["trace[745081] 'process raft request' (duration: 56.594782ms)","trace[745081] 'compare' (duration: 178.407743ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:50:23.076391Z","caller":"traceutil/trace.go:171","msg":"trace[508970296] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"213.596684ms","start":"2026-08-05T21:50:22.862755Z","end":"2026-08-05T21:50:23.076352Z","steps":["trace[508970296] 'process raft request' (duration: 213.312379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:23.076598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.322853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7zjhp\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-05T21:50:23.076683Z","caller":"traceutil/trace.go:171","msg":"trace[133385674] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7zjhp; range_end:; response_count:1; response_revision:5006; }","duration":"206.447276ms","start":"2026-08-05T21:50:22.870217Z","end":"2026-08-05T21:50:23.076664Z","steps":["trace[133385674] 'agreement among raft nodes before linearized reading' (duration: 206.224982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:23.087128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.365027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-08-05T21:50:23.087169Z","caller":"traceutil/trace.go:171","msg":"trace[1713263356] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5007; }","duration":"160.435598ms","start":"2026-08-05T21:50:22.926722Z","end":"2026-08-05T21:50:23.087158Z","steps":["trace[1713263356] 'agreement among raft nodes before linearized reading' (duration: 159.644422ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:23.216859Z","caller":"traceutil/trace.go:171","msg":"trace[196335092] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"139.183326ms","start":"2026-08-05T21:50:23.077658Z","end":"2026-08-05T21:50:23.216841Z","steps":["trace[196335092] 'process raft request' (duration: 139.077444ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:23.217102Z","caller":"traceutil/trace.go:171","msg":"trace[1914444786] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5202; }","duration":"130.828875ms","start":"2026-08-05T21:50:23.086262Z","end":"2026-08-05T21:50:23.217091Z","steps":["trace[1914444786] 'read index received' (duration: 130.821995ms)","trace[1914444786] 'applied index is now lower than readState.Index' (duration: 5.2µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:50:23.2173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.071152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-08-05T21:50:23.217338Z","caller":"traceutil/trace.go:171","msg":"trace[478547271] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5008; }","duration":"129.140553ms","start":"2026-08-05T21:50:23.088185Z","end":"2026-08-05T21:50:23.217326Z","steps":["trace[478547271] 'agreement among raft nodes before linearized reading' (duration: 128.967459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:23.217351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.709349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62869"} {"level":"info","ts":"2026-08-05T21:50:23.21738Z","caller":"traceutil/trace.go:171","msg":"trace[268181054] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5008; }","duration":"135.78505ms","start":"2026-08-05T21:50:23.081588Z","end":"2026-08-05T21:50:23.217373Z","steps":["trace[268181054] 'agreement among raft nodes before linearized reading' (duration: 135.628777ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.007182Z","caller":"traceutil/trace.go:171","msg":"trace[1819802743] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"147.291493ms","start":"2026-08-05T21:50:31.859861Z","end":"2026-08-05T21:50:32.007153Z","steps":["trace[1819802743] 'process raft request' (duration: 74.352185ms)","trace[1819802743] 'compare' (duration: 72.747874ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:50:32.00743Z","caller":"traceutil/trace.go:171","msg":"trace[2107713015] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"147.447366ms","start":"2026-08-05T21:50:31.85994Z","end":"2026-08-05T21:50:32.007388Z","steps":["trace[2107713015] 'process raft request' (duration: 147.172411ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.007605Z","caller":"traceutil/trace.go:171","msg":"trace[540170616] transaction","detail":"{read_only:false; response_revision:5195; number_of_response:1; }","duration":"147.253213ms","start":"2026-08-05T21:50:31.860329Z","end":"2026-08-05T21:50:32.007583Z","steps":["trace[540170616] 'process raft request' (duration: 146.931227ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:51:46.918026Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-08-05T21:51:46.988924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"69.370139ms","hash":1445298630,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":33845248,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T21:51:46.988998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1445298630,"revision":2959,"compact-revision":-1} {"level":"warn","ts":"2026-08-05T21:56:40.824191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.009986ms","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":"warn","ts":"2026-08-05T21:56:40.824204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.753354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:56:40.824275Z","caller":"traceutil/trace.go:171","msg":"trace[1944406895] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8222; }","duration":"417.145408ms","start":"2026-08-05T21:56:40.40711Z","end":"2026-08-05T21:56:40.824256Z","steps":["trace[1944406895] 'count revisions from in-memory index tree' (duration: 416.890654ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:40.824284Z","caller":"traceutil/trace.go:171","msg":"trace[1948978183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8222; }","duration":"355.873556ms","start":"2026-08-05T21:56:40.468392Z","end":"2026-08-05T21:56:40.824265Z","steps":["trace[1948978183] 'range keys from in-memory index tree' (duration: 355.676223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:40.824314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:56:40.407082Z","time spent":"417.22089ms","remote":"127.0.0.1:45202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true "} {"level":"warn","ts":"2026-08-05T21:56:40.824325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:56:40.468374Z","time spent":"355.941337ms","remote":"127.0.0.1:45336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T21:56:40.824446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.432129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T21:56:40.824486Z","caller":"traceutil/trace.go:171","msg":"trace[1467029689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8222; }","duration":"156.473949ms","start":"2026-08-05T21:56:40.668001Z","end":"2026-08-05T21:56:40.824475Z","steps":["trace[1467029689] 'range keys from in-memory index tree' (duration: 156.264085ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:46.923451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6032} {"level":"info","ts":"2026-08-05T21:56:47.010016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6032,"took":"85.198603ms","hash":779725347,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":23326720,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-05T21:56:47.010062Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779725347,"revision":6032,"compact-revision":2959} {"level":"warn","ts":"2026-08-05T21:56:52.623001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.32385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:56:52.623287Z","caller":"traceutil/trace.go:171","msg":"trace[818284008] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"375.192059ms","start":"2026-08-05T21:56:52.248026Z","end":"2026-08-05T21:56:52.623218Z","steps":["trace[818284008] 'process raft request' (duration: 252.548813ms)","trace[818284008] 'compare' (duration: 122.191278ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:56:52.623362Z","caller":"traceutil/trace.go:171","msg":"trace[226820642] linearizableReadLoop","detail":"{readStateIndex:8583; appliedIndex:8579; }","duration":"169.00844ms","start":"2026-08-05T21:56:52.454324Z","end":"2026-08-05T21:56:52.623332Z","steps":["trace[226820642] 'read index received' (duration: 46.236101ms)","trace[226820642] 'applied index is now lower than readState.Index' (duration: 122.770919ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:56:52.62352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:56:52.248001Z","time spent":"375.395333ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:56:52.623651Z","caller":"traceutil/trace.go:171","msg":"trace[1186823105] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"374.194191ms","start":"2026-08-05T21:56:52.249353Z","end":"2026-08-05T21:56:52.623547Z","steps":["trace[1186823105] 'process raft request' (duration: 373.832824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:52.624045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:56:52.249343Z","time spent":"374.489516ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:56:52.624387Z","caller":"traceutil/trace.go:171","msg":"trace[249922538] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"376.29554ms","start":"2026-08-05T21:56:52.248039Z","end":"2026-08-05T21:56:52.624335Z","steps":["trace[249922538] 'process raft request' (duration: 375.075887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:52.624456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.120521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:56:52.62453Z","caller":"traceutil/trace.go:171","msg":"trace[1144785212] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8287; }","duration":"170.217304ms","start":"2026-08-05T21:56:52.454293Z","end":"2026-08-05T21:56:52.624511Z","steps":["trace[1144785212] 'agreement among raft nodes before linearized reading' (duration: 170.076771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:52.624596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:56:52.248018Z","time spent":"376.458443ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:56:52.624422Z","caller":"traceutil/trace.go:171","msg":"trace[70899427] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"374.468254ms","start":"2026-08-05T21:56:52.249921Z","end":"2026-08-05T21:56:52.624389Z","steps":["trace[70899427] 'process raft request' (duration: 373.334263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:52.624849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:56:52.249916Z","time spent":"374.857732ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:56:52.625134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.333256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:56:52.62557Z","caller":"traceutil/trace.go:171","msg":"trace[155145220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8287; }","duration":"158.792994ms","start":"2026-08-05T21:56:52.466754Z","end":"2026-08-05T21:56:52.625547Z","steps":["trace[155145220] 'agreement among raft nodes before linearized reading' (duration: 158.305905ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:54.73548Z","caller":"traceutil/trace.go:171","msg":"trace[631323745] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"103.67934ms","start":"2026-08-05T21:56:54.631769Z","end":"2026-08-05T21:56:54.735448Z","steps":["trace[631323745] 'process raft request' (duration: 103.492056ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:54.740807Z","caller":"traceutil/trace.go:171","msg":"trace[87105299] transaction","detail":"{read_only:false; response_revision:8295; number_of_response:1; }","duration":"108.049975ms","start":"2026-08-05T21:56:54.632731Z","end":"2026-08-05T21:56:54.740781Z","steps":["trace[87105299] 'process raft request' (duration: 107.898432ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:54.741129Z","caller":"traceutil/trace.go:171","msg":"trace[1207318080] transaction","detail":"{read_only:false; response_revision:8297; number_of_response:1; }","duration":"107.528185ms","start":"2026-08-05T21:56:54.63358Z","end":"2026-08-05T21:56:54.741108Z","steps":["trace[1207318080] 'process raft request' (duration: 107.418563ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:54.741197Z","caller":"traceutil/trace.go:171","msg":"trace[149055030] transaction","detail":"{read_only:false; response_revision:8296; number_of_response:1; }","duration":"108.059436ms","start":"2026-08-05T21:56:54.63307Z","end":"2026-08-05T21:56:54.741129Z","steps":["trace[149055030] 'process raft request' (duration: 107.665598ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:33.876651Z","caller":"traceutil/trace.go:171","msg":"trace[841689566] linearizableReadLoop","detail":"{readStateIndex:8910; appliedIndex:8909; }","duration":"101.690082ms","start":"2026-08-05T21:57:33.774931Z","end":"2026-08-05T21:57:33.876622Z","steps":["trace[841689566] 'read index received' (duration: 61.920925ms)","trace[841689566] 'applied index is now lower than readState.Index' (duration: 39.768077ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:57:33.87673Z","caller":"traceutil/trace.go:171","msg":"trace[1737346611] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"115.06323ms","start":"2026-08-05T21:57:33.761636Z","end":"2026-08-05T21:57:33.876699Z","steps":["trace[1737346611] 'process raft request' (duration: 75.1407ms)","trace[1737346611] 'compare' (duration: 39.705946ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:57:33.876999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.858195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:57:33.877055Z","caller":"traceutil/trace.go:171","msg":"trace[361139621] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8601; }","duration":"102.180371ms","start":"2026-08-05T21:57:33.774861Z","end":"2026-08-05T21:57:33.877042Z","steps":["trace[361139621] 'agreement among raft nodes before linearized reading' (duration: 101.875485ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:34.875352Z","caller":"traceutil/trace.go:171","msg":"trace[1986961184] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"128.445779ms","start":"2026-08-05T21:57:34.746883Z","end":"2026-08-05T21:57:34.875328Z","steps":["trace[1986961184] 'process raft request' (duration: 128.300815ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:58:14.402076Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002457.wal"} {"level":"info","ts":"2026-08-05T21:58:33.361286Z","caller":"traceutil/trace.go:171","msg":"trace[1117851262] transaction","detail":"{read_only:false; response_revision:9233; number_of_response:1; }","duration":"140.625306ms","start":"2026-08-05T21:58:33.220631Z","end":"2026-08-05T21:58:33.361256Z","steps":["trace[1117851262] 'process raft request' (duration: 140.413232ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:58:33.433067Z","caller":"traceutil/trace.go:171","msg":"trace[1033566356] linearizableReadLoop","detail":"{readStateIndex:9560; appliedIndex:9558; }","duration":"174.238146ms","start":"2026-08-05T21:58:33.258803Z","end":"2026-08-05T21:58:33.433041Z","steps":["trace[1033566356] 'read index received' (duration: 102.279106ms)","trace[1033566356] 'applied index is now lower than readState.Index' (duration: 71.95814ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:58:33.433187Z","caller":"traceutil/trace.go:171","msg":"trace[1003840052] transaction","detail":"{read_only:false; response_revision:9234; number_of_response:1; }","duration":"212.107908ms","start":"2026-08-05T21:58:33.221052Z","end":"2026-08-05T21:58:33.43316Z","steps":["trace[1003840052] 'process raft request' (duration: 152.588868ms)","trace[1003840052] 'compare' (duration: 59.01271ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:58:33.433435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.386578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-05T21:58:33.433518Z","caller":"traceutil/trace.go:171","msg":"trace[873993563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9234; }","duration":"174.505201ms","start":"2026-08-05T21:58:33.258996Z","end":"2026-08-05T21:58:33.433501Z","steps":["trace[873993563] 'agreement among raft nodes before linearized reading' (duration: 174.254296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:33.433701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.909939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T21:58:33.433736Z","caller":"traceutil/trace.go:171","msg":"trace[542647745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9234; }","duration":"174.943119ms","start":"2026-08-05T21:58:33.258783Z","end":"2026-08-05T21:58:33.433726Z","steps":["trace[542647745] 'agreement among raft nodes before linearized reading' (duration: 174.853988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:58:33.433763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.828341ms","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-08-05T21:58:33.43385Z","caller":"traceutil/trace.go:171","msg":"trace[1417351419] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9234; }","duration":"169.949653ms","start":"2026-08-05T21:58:33.26388Z","end":"2026-08-05T21:58:33.43383Z","steps":["trace[1417351419] 'agreement among raft nodes before linearized reading' (duration: 169.715639ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:59:31.177657Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"864e14457b828f9c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T21:59:31.184352Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T21:59:31.18458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T22:01:46.929584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8258} {"level":"info","ts":"2026-08-05T22:01:47.014174Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8258,"took":"82.186792ms","hash":147772042,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":26345472,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-05T22:01:47.014247Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":147772042,"revision":8258,"compact-revision":6032} {"level":"warn","ts":"2026-08-05T22:02:24.826995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.553746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T22:02:24.827107Z","caller":"traceutil/trace.go:171","msg":"trace[92732065] linearizableReadLoop","detail":"{readStateIndex:11538; appliedIndex:11536; }","duration":"245.250364ms","start":"2026-08-05T22:02:24.58184Z","end":"2026-08-05T22:02:24.827091Z","steps":["trace[92732065] 'read index received' (duration: 46.796944ms)","trace[92732065] 'applied index is now lower than readState.Index' (duration: 198.45192ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:02:24.827179Z","caller":"traceutil/trace.go:171","msg":"trace[515234438] transaction","detail":"{read_only:false; response_revision:11155; number_of_response:1; }","duration":"272.458209ms","start":"2026-08-05T22:02:24.554706Z","end":"2026-08-05T22:02:24.827165Z","steps":["trace[515234438] 'process raft request' (duration: 166.654888ms)","trace[515234438] 'compare' (duration: 105.447935ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:02:24.827426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.57229ms","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-08-05T22:02:24.82749Z","caller":"traceutil/trace.go:171","msg":"trace[721120964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11155; }","duration":"245.674981ms","start":"2026-08-05T22:02:24.581801Z","end":"2026-08-05T22:02:24.827476Z","steps":["trace[721120964] 'agreement among raft nodes before linearized reading' (duration: 245.497418ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.988339Z","caller":"traceutil/trace.go:171","msg":"trace[863917027] linearizableReadLoop","detail":"{readStateIndex:11539; appliedIndex:11538; }","duration":"161.184701ms","start":"2026-08-05T22:02:24.827133Z","end":"2026-08-05T22:02:24.988317Z","steps":["trace[863917027] 'read index received' (duration: 121.063457ms)","trace[863917027] 'applied index is now lower than readState.Index' (duration: 40.119694ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:02:24.988489Z","caller":"traceutil/trace.go:171","msg":"trace[1991672082] transaction","detail":"{read_only:false; response_revision:11156; number_of_response:1; }","duration":"195.520214ms","start":"2026-08-05T22:02:24.792955Z","end":"2026-08-05T22:02:24.988475Z","steps":["trace[1991672082] 'process raft request' (duration: 155.162276ms)","trace[1991672082] 'compare' (duration: 40.010362ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:02:24.988806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.649883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:02:24.988852Z","caller":"traceutil/trace.go:171","msg":"trace[474807210] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11157; }","duration":"157.733034ms","start":"2026-08-05T22:02:24.831108Z","end":"2026-08-05T22:02:24.988841Z","steps":["trace[474807210] 'agreement among raft nodes before linearized reading' (duration: 157.627242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.988863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.531696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T22:02:24.988993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.542242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-08-05T22:02:24.988985Z","caller":"traceutil/trace.go:171","msg":"trace[1984276385] transaction","detail":"{read_only:false; response_revision:11157; number_of_response:1; }","duration":"157.366537ms","start":"2026-08-05T22:02:24.831579Z","end":"2026-08-05T22:02:24.988946Z","steps":["trace[1984276385] 'process raft request' (duration: 156.686554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.257832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-08-05T22:02:24.989068Z","caller":"traceutil/trace.go:171","msg":"trace[1369140561] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11157; }","duration":"155.636693ms","start":"2026-08-05T22:02:24.833412Z","end":"2026-08-05T22:02:24.989049Z","steps":["trace[1369140561] 'agreement among raft nodes before linearized reading' (duration: 155.4496ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.989082Z","caller":"traceutil/trace.go:171","msg":"trace[1418519553] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11157; }","duration":"279.895163ms","start":"2026-08-05T22:02:24.709126Z","end":"2026-08-05T22:02:24.989021Z","steps":["trace[1418519553] 'agreement among raft nodes before linearized reading' (duration: 279.572187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.935728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T22:02:24.989155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.862797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-05T22:02:24.989068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.99904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:02:24.989206Z","caller":"traceutil/trace.go:171","msg":"trace[2110250432] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11157; }","duration":"158.04999ms","start":"2026-08-05T22:02:24.831147Z","end":"2026-08-05T22:02:24.989197Z","steps":["trace[2110250432] 'agreement among raft nodes before linearized reading' (duration: 157.976989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.717979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T22:02:24.989244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.535497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T22:02:24.988973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.872316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-05T22:02:24.989284Z","caller":"traceutil/trace.go:171","msg":"trace[999195124] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11157; }","duration":"278.794091ms","start":"2026-08-05T22:02:24.710473Z","end":"2026-08-05T22:02:24.989267Z","steps":["trace[999195124] 'agreement among raft nodes before linearized reading' (duration: 278.72376ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.989278Z","caller":"traceutil/trace.go:171","msg":"trace[1381642225] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11157; }","duration":"157.255405ms","start":"2026-08-05T22:02:24.831991Z","end":"2026-08-05T22:02:24.989246Z","steps":["trace[1381642225] 'agreement among raft nodes before linearized reading' (duration: 157.048141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.213694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:02:24.989321Z","caller":"traceutil/trace.go:171","msg":"trace[978669202] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:11157; }","duration":"175.66182ms","start":"2026-08-05T22:02:24.813633Z","end":"2026-08-05T22:02:24.989295Z","steps":["trace[978669202] 'agreement among raft nodes before linearized reading' (duration: 175.016228ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.989335Z","caller":"traceutil/trace.go:171","msg":"trace[1197261413] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11157; }","duration":"404.268033ms","start":"2026-08-05T22:02:24.585045Z","end":"2026-08-05T22:02:24.989313Z","steps":["trace[1197261413] 'agreement among raft nodes before linearized reading' (duration: 403.647481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.936865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-05T22:02:24.989257Z","caller":"traceutil/trace.go:171","msg":"trace[2068167259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11157; }","duration":"402.994239ms","start":"2026-08-05T22:02:24.586246Z","end":"2026-08-05T22:02:24.98924Z","steps":["trace[2068167259] 'agreement among raft nodes before linearized reading' (duration: 402.436698ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.989338Z","caller":"traceutil/trace.go:171","msg":"trace[899571959] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11157; }","duration":"157.479479ms","start":"2026-08-05T22:02:24.831849Z","end":"2026-08-05T22:02:24.989328Z","steps":["trace[899571959] 'agreement among raft nodes before linearized reading' (duration: 157.242505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.932825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T22:02:24.989394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.718762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T22:02:24.98965Z","caller":"traceutil/trace.go:171","msg":"trace[578857395] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:11157; }","duration":"104.025918ms","start":"2026-08-05T22:02:24.8856Z","end":"2026-08-05T22:02:24.989626Z","steps":["trace[578857395] 'agreement among raft nodes before linearized reading' (duration: 103.670751ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.989587Z","caller":"traceutil/trace.go:171","msg":"trace[1373695186] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11157; }","duration":"278.500035ms","start":"2026-08-05T22:02:24.711076Z","end":"2026-08-05T22:02:24.989576Z","steps":["trace[1373695186] 'agreement among raft nodes before linearized reading' (duration: 277.597678ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.989381Z","caller":"traceutil/trace.go:171","msg":"trace[1609911709] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11157; }","duration":"278.353712ms","start":"2026-08-05T22:02:24.711021Z","end":"2026-08-05T22:02:24.989375Z","steps":["trace[1609911709] 'agreement among raft nodes before linearized reading' (duration: 277.71341ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:02:24.989117Z","caller":"traceutil/trace.go:171","msg":"trace[175710566] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11157; }","duration":"277.387454ms","start":"2026-08-05T22:02:24.711704Z","end":"2026-08-05T22:02:24.989092Z","steps":["trace[175710566] 'agreement among raft nodes before linearized reading' (duration: 276.959676ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:02:24.585036Z","time spent":"404.343664ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-05T22:02:24.98911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.028776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:02:24.990456Z","caller":"traceutil/trace.go:171","msg":"trace[595856836] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11157; }","duration":"279.370373ms","start":"2026-08-05T22:02:24.71105Z","end":"2026-08-05T22:02:24.99042Z","steps":["trace[595856836] 'agreement among raft nodes before linearized reading' (duration: 278.026017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:24.989533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:02:24.586238Z","time spent":"403.274604ms","remote":"127.0.0.1:45582","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-08-05T22:06:46.93736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10763} {"level":"info","ts":"2026-08-05T22:06:47.0106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10763,"took":"70.345568ms","hash":1142606756,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":28291072,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T22:06:47.010667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1142606756,"revision":10763,"compact-revision":8258} {"level":"info","ts":"2026-08-05T22:08:42.814227Z","caller":"traceutil/trace.go:171","msg":"trace[258593432] transaction","detail":"{read_only:false; response_revision:13748; number_of_response:1; }","duration":"140.592685ms","start":"2026-08-05T22:08:42.67361Z","end":"2026-08-05T22:08:42.814202Z","steps":["trace[258593432] 'process raft request' (duration: 40.44684ms)","trace[258593432] 'compare' (duration: 99.844169ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:08:43.384549Z","caller":"traceutil/trace.go:171","msg":"trace[1227733152] transaction","detail":"{read_only:false; response_revision:13752; number_of_response:1; }","duration":"160.676204ms","start":"2026-08-05T22:08:43.223848Z","end":"2026-08-05T22:08:43.384524Z","steps":["trace[1227733152] 'process raft request' (duration: 160.512321ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:54.912619Z","caller":"traceutil/trace.go:171","msg":"trace[1073782406] transaction","detail":"{read_only:false; response_revision:13932; number_of_response:1; }","duration":"128.3552ms","start":"2026-08-05T22:08:54.784228Z","end":"2026-08-05T22:08:54.912584Z","steps":["trace[1073782406] 'process raft request' (duration: 128.110455ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:55.056777Z","caller":"traceutil/trace.go:171","msg":"trace[1608632938] transaction","detail":"{read_only:false; response_revision:13933; number_of_response:1; }","duration":"140.987093ms","start":"2026-08-05T22:08:54.915758Z","end":"2026-08-05T22:08:55.056745Z","steps":["trace[1608632938] 'process raft request' (duration: 133.491918ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:55.058514Z","caller":"traceutil/trace.go:171","msg":"trace[996379870] transaction","detail":"{read_only:false; response_revision:13934; number_of_response:1; }","duration":"142.621325ms","start":"2026-08-05T22:08:54.915875Z","end":"2026-08-05T22:08:55.058496Z","steps":["trace[996379870] 'process raft request' (duration: 142.498553ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:55.05886Z","caller":"traceutil/trace.go:171","msg":"trace[191908384] transaction","detail":"{read_only:false; response_revision:13936; number_of_response:1; }","duration":"142.619925ms","start":"2026-08-05T22:08:54.916226Z","end":"2026-08-05T22:08:55.058846Z","steps":["trace[191908384] 'process raft request' (duration: 142.467542ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:55.058843Z","caller":"traceutil/trace.go:171","msg":"trace[2125672940] linearizableReadLoop","detail":"{readStateIndex:14413; appliedIndex:14409; }","duration":"141.704818ms","start":"2026-08-05T22:08:54.917103Z","end":"2026-08-05T22:08:55.058808Z","steps":["trace[2125672940] 'read index received' (duration: 132.161564ms)","trace[2125672940] 'applied index is now lower than readState.Index' (duration: 9.541694ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:08:55.058924Z","caller":"traceutil/trace.go:171","msg":"trace[515459836] transaction","detail":"{read_only:false; response_revision:13935; number_of_response:1; }","duration":"143.009613ms","start":"2026-08-05T22:08:54.915898Z","end":"2026-08-05T22:08:55.058908Z","steps":["trace[515459836] 'process raft request' (duration: 142.554804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:08:55.059144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.017274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-vjxjq\" ","response":"range_response_count:1 size:7032"} {"level":"info","ts":"2026-08-05T22:08:55.059203Z","caller":"traceutil/trace.go:171","msg":"trace[1049426248] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-vjxjq; range_end:; response_count:1; response_revision:13936; }","duration":"142.121595ms","start":"2026-08-05T22:08:54.917068Z","end":"2026-08-05T22:08:55.05919Z","steps":["trace[1049426248] 'agreement among raft nodes before linearized reading' (duration: 141.913571ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:10:02.357409Z","caller":"traceutil/trace.go:171","msg":"trace[1552222661] transaction","detail":"{read_only:false; response_revision:14850; number_of_response:1; }","duration":"131.252936ms","start":"2026-08-05T22:10:02.22613Z","end":"2026-08-05T22:10:02.357383Z","steps":["trace[1552222661] 'process raft request' (duration: 103.01829ms)","trace[1552222661] 'compare' (duration: 27.711575ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:11:46.943418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12976} {"level":"info","ts":"2026-08-05T22:11:47.017284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12976,"took":"71.209768ms","hash":3831763408,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":31223808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:11:47.017435Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3831763408,"revision":12976,"compact-revision":10763} {"level":"info","ts":"2026-08-05T22:16:13.805516Z","caller":"traceutil/trace.go:171","msg":"trace[2051451055] linearizableReadLoop","detail":"{readStateIndex:18193; appliedIndex:18192; }","duration":"166.512169ms","start":"2026-08-05T22:16:13.638979Z","end":"2026-08-05T22:16:13.805492Z","steps":["trace[2051451055] 'read index received' (duration: 166.325016ms)","trace[2051451055] 'applied index is now lower than readState.Index' (duration: 185.943µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:16:13.80587Z","caller":"traceutil/trace.go:171","msg":"trace[1908641684] transaction","detail":"{read_only:false; response_revision:17596; number_of_response:1; }","duration":"244.787398ms","start":"2026-08-05T22:16:13.561069Z","end":"2026-08-05T22:16:13.805857Z","steps":["trace[1908641684] 'process raft request' (duration: 244.260818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:13.806165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.169281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-05T22:16:13.806211Z","caller":"traceutil/trace.go:171","msg":"trace[1850110359] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:17596; }","duration":"167.317165ms","start":"2026-08-05T22:16:13.638882Z","end":"2026-08-05T22:16:13.806199Z","steps":["trace[1850110359] 'agreement among raft nodes before linearized reading' (duration: 167.174402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:13.806506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.584866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-05T22:16:13.806622Z","caller":"traceutil/trace.go:171","msg":"trace[659390053] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17596; }","duration":"118.734209ms","start":"2026-08-05T22:16:13.687856Z","end":"2026-08-05T22:16:13.806591Z","steps":["trace[659390053] 'agreement among raft nodes before linearized reading' (duration: 118.448594ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:16:20.18855Z","caller":"traceutil/trace.go:171","msg":"trace[1316456278] transaction","detail":"{read_only:false; response_revision:17645; number_of_response:1; }","duration":"200.497335ms","start":"2026-08-05T22:16:19.988017Z","end":"2026-08-05T22:16:20.188515Z","steps":["trace[1316456278] 'process raft request' (duration: 126.495128ms)","trace[1316456278] 'compare' (duration: 73.556628ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:16:20.188577Z","caller":"traceutil/trace.go:171","msg":"trace[884372573] transaction","detail":"{read_only:false; response_revision:17646; number_of_response:1; }","duration":"197.60701ms","start":"2026-08-05T22:16:19.990938Z","end":"2026-08-05T22:16:20.188545Z","steps":["trace[884372573] 'process raft request' (duration: 197.495227ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:16:20.188669Z","caller":"traceutil/trace.go:171","msg":"trace[193365608] linearizableReadLoop","detail":"{readStateIndex:18244; appliedIndex:18242; }","duration":"160.991604ms","start":"2026-08-05T22:16:20.027647Z","end":"2026-08-05T22:16:20.188639Z","steps":["trace[193365608] 'read index received' (duration: 86.878294ms)","trace[193365608] 'applied index is now lower than readState.Index' (duration: 74.11056ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:16:20.188851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.191677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-08-05T22:16:20.188898Z","caller":"traceutil/trace.go:171","msg":"trace[202398999] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:17646; }","duration":"161.287969ms","start":"2026-08-05T22:16:20.027597Z","end":"2026-08-05T22:16:20.188885Z","steps":["trace[202398999] 'agreement among raft nodes before linearized reading' (duration: 161.131596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:20.188928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.395974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-05T22:16:20.188968Z","caller":"traceutil/trace.go:171","msg":"trace[2047051160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17646; }","duration":"104.490625ms","start":"2026-08-05T22:16:20.084467Z","end":"2026-08-05T22:16:20.188958Z","steps":["trace[2047051160] 'agreement among raft nodes before linearized reading' (duration: 104.321692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:20.675415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.601423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:16:20.675594Z","caller":"traceutil/trace.go:171","msg":"trace[1306180873] linearizableReadLoop","detail":"{readStateIndex:18248; appliedIndex:18247; }","duration":"208.684322ms","start":"2026-08-05T22:16:20.466891Z","end":"2026-08-05T22:16:20.675575Z","steps":["trace[1306180873] 'read index received' (duration: 31.34µs)","trace[1306180873] 'applied index is now lower than readState.Index' (duration: 208.651662ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:16:20.675661Z","caller":"traceutil/trace.go:171","msg":"trace[48935785] transaction","detail":"{read_only:false; response_revision:17649; number_of_response:1; }","duration":"283.532625ms","start":"2026-08-05T22:16:20.392083Z","end":"2026-08-05T22:16:20.675616Z","steps":["trace[48935785] 'process raft request' (duration: 36.649266ms)","trace[48935785] 'compare' (duration: 246.197846ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:16:20.675856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.949057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:16:20.675978Z","caller":"traceutil/trace.go:171","msg":"trace[1836193743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17649; }","duration":"209.104701ms","start":"2026-08-05T22:16:20.466856Z","end":"2026-08-05T22:16:20.67596Z","steps":["trace[1836193743] 'agreement among raft nodes before linearized reading' (duration: 208.779584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:20.677195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.254341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:132 size:1636056"} {"level":"info","ts":"2026-08-05T22:16:20.6773Z","caller":"traceutil/trace.go:171","msg":"trace[1852399091] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:132; response_revision:17649; }","duration":"186.400863ms","start":"2026-08-05T22:16:20.490883Z","end":"2026-08-05T22:16:20.677284Z","steps":["trace[1852399091] 'agreement among raft nodes before linearized reading' (duration: 184.819302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:27.561642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.358738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:16:27.561848Z","caller":"traceutil/trace.go:171","msg":"trace[953254259] transaction","detail":"{read_only:false; response_revision:17860; number_of_response:1; }","duration":"162.190596ms","start":"2026-08-05T22:16:27.399625Z","end":"2026-08-05T22:16:27.561816Z","steps":["trace[953254259] 'process raft request' (duration: 53.501501ms)","trace[953254259] 'compare' (duration: 108.185294ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:16:29.657908Z","caller":"traceutil/trace.go:171","msg":"trace[791085967] linearizableReadLoop","detail":"{readStateIndex:18492; appliedIndex:18491; }","duration":"107.123335ms","start":"2026-08-05T22:16:29.550759Z","end":"2026-08-05T22:16:29.657882Z","steps":["trace[791085967] 'read index received' (duration: 106.913961ms)","trace[791085967] 'applied index is now lower than readState.Index' (duration: 208.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:16:29.658144Z","caller":"traceutil/trace.go:171","msg":"trace[826048324] transaction","detail":"{read_only:false; response_revision:17889; number_of_response:1; }","duration":"107.87942ms","start":"2026-08-05T22:16:29.550231Z","end":"2026-08-05T22:16:29.65811Z","steps":["trace[826048324] 'process raft request' (duration: 107.520683ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:29.658414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.635564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-08-05T22:16:29.658511Z","caller":"traceutil/trace.go:171","msg":"trace[1609509670] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:17889; }","duration":"107.775397ms","start":"2026-08-05T22:16:29.550712Z","end":"2026-08-05T22:16:29.658487Z","steps":["trace[1609509670] 'agreement among raft nodes before linearized reading' (duration: 107.40167ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:16:46.949194Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15495} {"level":"info","ts":"2026-08-05T22:16:47.046001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15495,"took":"94.313298ms","hash":3019529061,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":33857536,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T22:16:47.046053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3019529061,"revision":15495,"compact-revision":12976} {"level":"info","ts":"2026-08-05T22:19:44.60417Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"864e14457b828f9c","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T22:19:44.612017Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T22:19:44.612103Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-08-05T22:21:13.112114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.366267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:21:13.112201Z","caller":"traceutil/trace.go:171","msg":"trace[2133301839] linearizableReadLoop","detail":"{readStateIndex:20623; appliedIndex:20622; }","duration":"117.429776ms","start":"2026-08-05T22:21:12.994759Z","end":"2026-08-05T22:21:13.112188Z","steps":["trace[2133301839] 'read index received' (duration: 53.421µs)","trace[2133301839] 'applied index is now lower than readState.Index' (duration: 117.375185ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:13.11226Z","caller":"traceutil/trace.go:171","msg":"trace[1750784735] transaction","detail":"{read_only:false; response_revision:19952; number_of_response:1; }","duration":"150.401361ms","start":"2026-08-05T22:21:12.961831Z","end":"2026-08-05T22:21:13.112232Z","steps":["trace[1750784735] 'compare' (duration: 141.048371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:13.11235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.598989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-05T22:21:13.112375Z","caller":"traceutil/trace.go:171","msg":"trace[832904689] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19952; }","duration":"117.659029ms","start":"2026-08-05T22:21:12.99471Z","end":"2026-08-05T22:21:13.112369Z","steps":["trace[832904689] 'agreement among raft nodes before linearized reading' (duration: 117.541087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:13.112429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.391725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-05T22:21:13.112492Z","caller":"traceutil/trace.go:171","msg":"trace[1301830896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19952; }","duration":"103.493246ms","start":"2026-08-05T22:21:13.008986Z","end":"2026-08-05T22:21:13.112479Z","steps":["trace[1301830896] 'agreement among raft nodes before linearized reading' (duration: 103.290692ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:13.446058Z","caller":"traceutil/trace.go:171","msg":"trace[1150236384] linearizableReadLoop","detail":"{readStateIndex:20624; appliedIndex:20623; }","duration":"329.470794ms","start":"2026-08-05T22:21:13.116562Z","end":"2026-08-05T22:21:13.446033Z","steps":["trace[1150236384] 'read index received' (duration: 257.288293ms)","trace[1150236384] 'applied index is now lower than readState.Index' (duration: 72.181631ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:13.446186Z","caller":"traceutil/trace.go:171","msg":"trace[130676932] transaction","detail":"{read_only:false; response_revision:19953; number_of_response:1; }","duration":"329.997805ms","start":"2026-08-05T22:21:13.11617Z","end":"2026-08-05T22:21:13.446168Z","steps":["trace[130676932] 'process raft request' (duration: 257.66895ms)","trace[130676932] 'compare' (duration: 71.846086ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:21:13.446294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.713199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18464"} {"level":"warn","ts":"2026-08-05T22:21:13.446327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:13.11616Z","time spent":"330.101047ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:21:13.446353Z","caller":"traceutil/trace.go:171","msg":"trace[1434121659] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19953; }","duration":"329.790361ms","start":"2026-08-05T22:21:13.116546Z","end":"2026-08-05T22:21:13.446336Z","steps":["trace[1434121659] 'agreement among raft nodes before linearized reading' (duration: 329.580757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:13.446404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:13.11654Z","time spent":"329.853513ms","remote":"127.0.0.1:45498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18487,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-05T22:21:13.44938Z","caller":"traceutil/trace.go:171","msg":"trace[797116764] transaction","detail":"{read_only:false; response_revision:19954; number_of_response:1; }","duration":"239.99089ms","start":"2026-08-05T22:21:13.209356Z","end":"2026-08-05T22:21:13.449347Z","steps":["trace[797116764] 'process raft request' (duration: 239.781615ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:13.449491Z","caller":"traceutil/trace.go:171","msg":"trace[1761574884] transaction","detail":"{read_only:false; response_revision:19955; number_of_response:1; }","duration":"237.35675ms","start":"2026-08-05T22:21:13.212116Z","end":"2026-08-05T22:21:13.449473Z","steps":["trace[1761574884] 'process raft request' (duration: 237.188996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:13.778922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.510802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:21:13.779016Z","caller":"traceutil/trace.go:171","msg":"trace[1795073761] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:19955; }","duration":"181.630604ms","start":"2026-08-05T22:21:13.597366Z","end":"2026-08-05T22:21:13.778996Z","steps":["trace[1795073761] 'count revisions from in-memory index tree' (duration: 181.453671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:13.779045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.008654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-05T22:21:13.779125Z","caller":"traceutil/trace.go:171","msg":"trace[361959255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19955; }","duration":"164.128585ms","start":"2026-08-05T22:21:13.614978Z","end":"2026-08-05T22:21:13.779106Z","steps":["trace[361959255] 'range keys from in-memory index tree' (duration: 163.840481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:14.319798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.247914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:21:14.319901Z","caller":"traceutil/trace.go:171","msg":"trace[864334688] transaction","detail":"{read_only:false; response_revision:19958; number_of_response:1; }","duration":"222.280858ms","start":"2026-08-05T22:21:14.097602Z","end":"2026-08-05T22:21:14.319882Z","steps":["trace[864334688] 'process raft request' (duration: 79.87161ms)","trace[864334688] 'compare' (duration: 142.065571ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:46.956217Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18074} {"level":"info","ts":"2026-08-05T22:21:47.066067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18074,"took":"106.24848ms","hash":3578941198,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":29597696,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T22:21:47.066123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3578941198,"revision":18074,"compact-revision":15495} {"level":"info","ts":"2026-08-05T22:24:57.528236Z","caller":"traceutil/trace.go:171","msg":"trace[1421976698] linearizableReadLoop","detail":"{readStateIndex:23045; appliedIndex:23044; }","duration":"191.096206ms","start":"2026-08-05T22:24:57.33708Z","end":"2026-08-05T22:24:57.528176Z","steps":["trace[1421976698] 'read index received' (duration: 190.714129ms)","trace[1421976698] 'applied index is now lower than readState.Index' (duration: 380.717µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:24:57.528343Z","caller":"traceutil/trace.go:171","msg":"trace[1480244424] transaction","detail":"{read_only:false; response_revision:22300; number_of_response:1; }","duration":"237.519182ms","start":"2026-08-05T22:24:57.290769Z","end":"2026-08-05T22:24:57.528289Z","steps":["trace[1480244424] 'process raft request' (duration: 237.162185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:24:57.528688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.583216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T22:24:57.528794Z","caller":"traceutil/trace.go:171","msg":"trace[66687728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22300; }","duration":"191.751678ms","start":"2026-08-05T22:24:57.337021Z","end":"2026-08-05T22:24:57.528773Z","steps":["trace[66687728] 'agreement among raft nodes before linearized reading' (duration: 191.32866ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:46.962476Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20208} {"level":"info","ts":"2026-08-05T22:26:47.069594Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20208,"took":"103.250632ms","hash":1763925073,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":43700224,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-05T22:26:47.069753Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1763925073,"revision":20208,"compact-revision":18074} {"level":"info","ts":"2026-08-05T22:31:46.968676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23430} {"level":"info","ts":"2026-08-05T22:31:47.096527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23430,"took":"124.647364ms","hash":1278698287,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":30617600,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:31:47.096585Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1278698287,"revision":23430,"compact-revision":20208}