{"level":"warn","ts":"2026-08-14T23:12:42.856754Z","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-14T23:12:42.856868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-14T23:12:42.856961Z","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-14T23:12:42.856982Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-14T23:12:42.857009Z","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-14T23:12:42.857853Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-08-14T23:12:42.858022Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-14T23:12:42.862516Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.05308ms"} {"level":"info","ts":"2026-08-14T23:12:42.867451Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-08-14T23:12:42.867505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T23:12:42.867531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-08-14T23:12:42.867545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T23:12:42.867556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-08-14T23:12:42.86759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-08-14T23:12:42.873047Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T23:12:42.874837Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T23:12:42.876228Z","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-14T23:12:42.879073Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T23:12:42.879227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T23:12:42.879911Z","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-14T23:12:42.880639Z","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-14T23:12:42.880695Z","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-14T23:12:42.880816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-08-14T23:12:42.881052Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-08-14T23:12:42.884063Z","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-14T23:12:42.884337Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-14T23:12:42.884468Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-08-14T23:12:42.884345Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T23:12:42.884423Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T23:12:43.468514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T23:12:43.468579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T23:12:43.46872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-08-14T23:12:43.468755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-08-14T23:12:43.468858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-14T23:12:43.468993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-08-14T23:12:43.469018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-08-14T23:12:43.469988Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:12:43.470799Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:12:43.470791Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T23:12:43.47089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T23:12:43.471249Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T23:12:43.471298Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T23:12:43.471432Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:12:43.471592Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:12:43.471646Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T23:12:43.474634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-14T23:12:43.475169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-08-14T23:13:18.925854Z","caller":"traceutil/trace.go:171","msg":"trace[2037324187] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"166.118364ms","start":"2026-08-14T23:13:18.75971Z","end":"2026-08-14T23:13:18.925829Z","steps":["trace[2037324187] 'process raft request' (duration: 159.734963ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:13:18.929463Z","caller":"traceutil/trace.go:171","msg":"trace[1616000748] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"156.216628ms","start":"2026-08-14T23:13:18.773216Z","end":"2026-08-14T23:13:18.929433Z","steps":["trace[1616000748] 'process raft request' (duration: 155.926971ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:13:18.92947Z","caller":"traceutil/trace.go:171","msg":"trace[1663937050] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"151.239555ms","start":"2026-08-14T23:13:18.778203Z","end":"2026-08-14T23:13:18.929443Z","steps":["trace[1663937050] 'process raft request' (duration: 151.087251ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:13:18.930218Z","caller":"traceutil/trace.go:171","msg":"trace[990914345] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"150.502395ms","start":"2026-08-14T23:13:18.779696Z","end":"2026-08-14T23:13:18.930198Z","steps":["trace[990914345] 'process raft request' (duration: 149.671543ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:13:25.650368Z","caller":"traceutil/trace.go:171","msg":"trace[1944608317] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"282.169295ms","start":"2026-08-14T23:13:25.368178Z","end":"2026-08-14T23:13:25.650347Z","steps":["trace[1944608317] 'read index received' (duration: 279.642977ms)","trace[1944608317] 'applied index is now lower than readState.Index' (duration: 2.525378ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:13:25.650477Z","caller":"traceutil/trace.go:171","msg":"trace[376316741] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"431.624451ms","start":"2026-08-14T23:13:25.218824Z","end":"2026-08-14T23:13:25.650449Z","steps":["trace[376316741] 'process raft request' (duration: 429.574096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:13:25.650584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.39565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vmv9d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-14T23:13:25.650664Z","caller":"traceutil/trace.go:171","msg":"trace[83725945] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vmv9d; range_end:; response_count:1; response_revision:629; }","duration":"282.527534ms","start":"2026-08-14T23:13:25.368125Z","end":"2026-08-14T23:13:25.650652Z","steps":["trace[83725945] 'agreement among raft nodes before linearized reading' (duration: 282.345589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:13:25.650666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.055923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-14T23:13:25.650727Z","caller":"traceutil/trace.go:171","msg":"trace[1525035129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"263.157065ms","start":"2026-08-14T23:13:25.387551Z","end":"2026-08-14T23:13:25.650708Z","steps":["trace[1525035129] 'agreement among raft nodes before linearized reading' (duration: 262.97228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:13:25.65121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:13:25.218798Z","time spent":"431.772195ms","remote":"127.0.0.1:48500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:13:40.806898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.371968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:13:40.807054Z","caller":"traceutil/trace.go:171","msg":"trace[1432540980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"223.570284ms","start":"2026-08-14T23:13:40.583462Z","end":"2026-08-14T23:13:40.807032Z","steps":["trace[1432540980] 'range keys from in-memory index tree' (duration: 223.271416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:14:55.255864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.050888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-08-14T23:14:55.255899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.520819ms","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-14T23:14:55.255974Z","caller":"traceutil/trace.go:171","msg":"trace[1476537710] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1847; }","duration":"122.198573ms","start":"2026-08-14T23:14:55.133741Z","end":"2026-08-14T23:14:55.255939Z","steps":["trace[1476537710] 'range keys from in-memory index tree' (duration: 121.812163ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:14:55.256018Z","caller":"traceutil/trace.go:171","msg":"trace[686608585] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1847; }","duration":"228.706814ms","start":"2026-08-14T23:14:55.02728Z","end":"2026-08-14T23:14:55.255987Z","steps":["trace[686608585] 'range keys from in-memory index tree' (duration: 228.292863ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:14:55.418399Z","caller":"traceutil/trace.go:171","msg":"trace[1874730567] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"157.677605ms","start":"2026-08-14T23:14:55.260697Z","end":"2026-08-14T23:14:55.418375Z","steps":["trace[1874730567] 'process raft request' (duration: 157.411368ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:14:55.418628Z","caller":"traceutil/trace.go:171","msg":"trace[907092311] linearizableReadLoop","detail":"{readStateIndex:1919; appliedIndex:1918; }","duration":"126.216819ms","start":"2026-08-14T23:14:55.292096Z","end":"2026-08-14T23:14:55.418313Z","steps":["trace[907092311] 'read index received' (duration: 125.90685ms)","trace[907092311] 'applied index is now lower than readState.Index' (duration: 308.348µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:14:55.418668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.546698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-14T23:14:55.418794Z","caller":"traceutil/trace.go:171","msg":"trace[2124872885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1848; }","duration":"126.757692ms","start":"2026-08-14T23:14:55.292011Z","end":"2026-08-14T23:14:55.418769Z","steps":["trace[2124872885] 'agreement among raft nodes before linearized reading' (duration: 126.455715ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:15:03.9384Z","caller":"traceutil/trace.go:171","msg":"trace[1769611731] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"112.074518ms","start":"2026-08-14T23:15:03.826299Z","end":"2026-08-14T23:15:03.938374Z","steps":["trace[1769611731] 'process raft request' (duration: 111.900433ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:15:40.570952Z","caller":"traceutil/trace.go:171","msg":"trace[1585508158] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"152.969116ms","start":"2026-08-14T23:15:40.417941Z","end":"2026-08-14T23:15:40.57091Z","steps":["trace[1585508158] 'process raft request' (duration: 152.685339ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:16:29.666417Z","caller":"traceutil/trace.go:171","msg":"trace[2119516950] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"120.296553ms","start":"2026-08-14T23:16:29.546082Z","end":"2026-08-14T23:16:29.666378Z","steps":["trace[2119516950] 'process raft request' (duration: 73.288235ms)","trace[2119516950] 'compare' (duration: 46.874905ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:16:33.125102Z","caller":"traceutil/trace.go:171","msg":"trace[1739585879] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"100.116186ms","start":"2026-08-14T23:16:33.024959Z","end":"2026-08-14T23:16:33.125075Z","steps":["trace[1739585879] 'process raft request' (duration: 99.858979ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:17:07.418253Z","caller":"traceutil/trace.go:171","msg":"trace[1364093650] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"106.085322ms","start":"2026-08-14T23:17:07.312129Z","end":"2026-08-14T23:17:07.418214Z","steps":["trace[1364093650] 'process raft request' (duration: 105.838525ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:32.028325Z","caller":"traceutil/trace.go:171","msg":"trace[1366056948] transaction","detail":"{read_only:false; response_revision:3775; number_of_response:1; }","duration":"138.781101ms","start":"2026-08-14T23:19:31.889518Z","end":"2026-08-14T23:19:32.028299Z","steps":["trace[1366056948] 'process raft request' (duration: 138.555305ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:19:32.037571Z","caller":"traceutil/trace.go:171","msg":"trace[1416493303] linearizableReadLoop","detail":"{readStateIndex:3920; appliedIndex:3918; }","duration":"131.517099ms","start":"2026-08-14T23:19:31.906039Z","end":"2026-08-14T23:19:32.037556Z","steps":["trace[1416493303] 'read index received' (duration: 122.044729ms)","trace[1416493303] 'applied index is now lower than readState.Index' (duration: 9.47135ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:19:32.037691Z","caller":"traceutil/trace.go:171","msg":"trace[1691939818] transaction","detail":"{read_only:false; response_revision:3776; number_of_response:1; }","duration":"147.489711ms","start":"2026-08-14T23:19:31.890179Z","end":"2026-08-14T23:19:32.037668Z","steps":["trace[1691939818] 'process raft request' (duration: 147.297876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:19:32.037726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.665924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-14T23:19:32.037933Z","caller":"traceutil/trace.go:171","msg":"trace[1858739744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3776; }","duration":"131.941081ms","start":"2026-08-14T23:19:31.905978Z","end":"2026-08-14T23:19:32.037919Z","steps":["trace[1858739744] 'agreement among raft nodes before linearized reading' (duration: 131.626942ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:53.988321Z","caller":"traceutil/trace.go:171","msg":"trace[1787438485] linearizableReadLoop","detail":"{readStateIndex:4978; appliedIndex:4977; }","duration":"103.401829ms","start":"2026-08-14T23:20:53.884883Z","end":"2026-08-14T23:20:53.988285Z","steps":["trace[1787438485] 'read index received' (duration: 103.233434ms)","trace[1787438485] 'applied index is now lower than readState.Index' (duration: 166.524µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:20:53.988505Z","caller":"traceutil/trace.go:171","msg":"trace[874385802] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"107.142658ms","start":"2026-08-14T23:20:53.881327Z","end":"2026-08-14T23:20:53.988469Z","steps":["trace[874385802] 'process raft request' (duration: 106.753848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:20:53.988839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.862551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-14T23:20:53.988909Z","caller":"traceutil/trace.go:171","msg":"trace[186055897] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4797; }","duration":"104.059187ms","start":"2026-08-14T23:20:53.884832Z","end":"2026-08-14T23:20:53.988891Z","steps":["trace[186055897] 'agreement among raft nodes before linearized reading' (duration: 103.666836ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:54.021111Z","caller":"traceutil/trace.go:171","msg":"trace[1661049478] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"135.610775ms","start":"2026-08-14T23:20:53.885479Z","end":"2026-08-14T23:20:54.02109Z","steps":["trace[1661049478] 'process raft request' (duration: 135.367978ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:54.021178Z","caller":"traceutil/trace.go:171","msg":"trace[854543585] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"128.713962ms","start":"2026-08-14T23:20:53.892432Z","end":"2026-08-14T23:20:54.021146Z","steps":["trace[854543585] 'process raft request' (duration: 128.573338ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:54.021374Z","caller":"traceutil/trace.go:171","msg":"trace[193525348] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"126.888743ms","start":"2026-08-14T23:20:53.894473Z","end":"2026-08-14T23:20:54.021362Z","steps":["trace[193525348] 'process raft request' (duration: 126.607385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:20:54.021605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.534303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-14T23:20:54.02169Z","caller":"traceutil/trace.go:171","msg":"trace[1982362098] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4800; }","duration":"135.678507ms","start":"2026-08-14T23:20:53.885993Z","end":"2026-08-14T23:20:54.021672Z","steps":["trace[1982362098] 'agreement among raft nodes before linearized reading' (duration: 135.437401ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:58.589358Z","caller":"traceutil/trace.go:171","msg":"trace[460326818] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4960; }","duration":"133.974432ms","start":"2026-08-14T23:20:58.455348Z","end":"2026-08-14T23:20:58.589322Z","steps":["trace[460326818] 'process raft request' (duration: 133.851508ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:58.736114Z","caller":"traceutil/trace.go:171","msg":"trace[1489232502] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"142.914809ms","start":"2026-08-14T23:20:58.593175Z","end":"2026-08-14T23:20:58.73609Z","steps":["trace[1489232502] 'process raft request' (duration: 142.760655ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:58.788959Z","caller":"traceutil/trace.go:171","msg":"trace[1130266904] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"195.355913ms","start":"2026-08-14T23:20:58.593571Z","end":"2026-08-14T23:20:58.788927Z","steps":["trace[1130266904] 'process raft request' (duration: 195.077065ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:58.789073Z","caller":"traceutil/trace.go:171","msg":"trace[1317216793] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"177.54544ms","start":"2026-08-14T23:20:58.611494Z","end":"2026-08-14T23:20:58.78904Z","steps":["trace[1317216793] 'process raft request' (duration: 177.293063ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:58.789096Z","caller":"traceutil/trace.go:171","msg":"trace[605252398] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5148; }","duration":"184.816243ms","start":"2026-08-14T23:20:58.604232Z","end":"2026-08-14T23:20:58.789048Z","steps":["trace[605252398] 'read index received' (duration: 131.769673ms)","trace[605252398] 'applied index is now lower than readState.Index' (duration: 53.04293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:20:58.789341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.08218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-t9dtz-tls\" ","response":"range_response_count:1 size:4424"} {"level":"info","ts":"2026-08-14T23:20:58.78941Z","caller":"traceutil/trace.go:171","msg":"trace[192817285] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-t9dtz-tls; range_end:; response_count:1; response_revision:4963; }","duration":"185.198303ms","start":"2026-08-14T23:20:58.604196Z","end":"2026-08-14T23:20:58.789394Z","steps":["trace[192817285] 'agreement among raft nodes before linearized reading' (duration: 184.994898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:20:58.789514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.144402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f6afe00d-1462-4086-90c0-1b04f3cdd7e7\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-14T23:20:58.789568Z","caller":"traceutil/trace.go:171","msg":"trace[85823489] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f6afe00d-1462-4086-90c0-1b04f3cdd7e7; range_end:; response_count:1; response_revision:4963; }","duration":"100.274275ms","start":"2026-08-14T23:20:58.68928Z","end":"2026-08-14T23:20:58.789555Z","steps":["trace[85823489] 'agreement among raft nodes before linearized reading' (duration: 100.001478ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:59.149241Z","caller":"traceutil/trace.go:171","msg":"trace[828037907] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5159; }","duration":"194.392888ms","start":"2026-08-14T23:20:58.954822Z","end":"2026-08-14T23:20:59.149215Z","steps":["trace[828037907] 'read index received' (duration: 144.969114ms)","trace[828037907] 'applied index is now lower than readState.Index' (duration: 49.422254ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:20:59.149292Z","caller":"traceutil/trace.go:171","msg":"trace[1183672924] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"222.819354ms","start":"2026-08-14T23:20:58.92644Z","end":"2026-08-14T23:20:59.14926Z","steps":["trace[1183672924] 'process raft request' (duration: 222.717801ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:59.149403Z","caller":"traceutil/trace.go:171","msg":"trace[1426042876] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"224.17761ms","start":"2026-08-14T23:20:58.925191Z","end":"2026-08-14T23:20:59.149369Z","steps":["trace[1426042876] 'process raft request' (duration: 174.626073ms)","trace[1426042876] 'compare' (duration: 49.151686ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:20:59.149563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.714296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h4cmj\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-08-14T23:20:59.149587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.565166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-14T23:20:59.14961Z","caller":"traceutil/trace.go:171","msg":"trace[1167611877] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h4cmj; range_end:; response_count:1; response_revision:4973; }","duration":"194.82632ms","start":"2026-08-14T23:20:58.954773Z","end":"2026-08-14T23:20:59.149599Z","steps":["trace[1167611877] 'agreement among raft nodes before linearized reading' (duration: 194.599374ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:20:59.149639Z","caller":"traceutil/trace.go:171","msg":"trace[750423432] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4973; }","duration":"105.67865ms","start":"2026-08-14T23:20:59.043945Z","end":"2026-08-14T23:20:59.149624Z","steps":["trace[750423432] 'agreement among raft nodes before linearized reading' (duration: 105.488225ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.084368Z","caller":"traceutil/trace.go:171","msg":"trace[209756850] transaction","detail":"{read_only:false; response_revision:5222; number_of_response:1; }","duration":"145.827697ms","start":"2026-08-14T23:21:10.938517Z","end":"2026-08-14T23:21:11.084345Z","steps":["trace[209756850] 'process raft request' (duration: 145.788346ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.084681Z","caller":"traceutil/trace.go:171","msg":"trace[930902418] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"147.622445ms","start":"2026-08-14T23:21:10.937009Z","end":"2026-08-14T23:21:11.084632Z","steps":["trace[930902418] 'process raft request' (duration: 147.157192ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.084695Z","caller":"traceutil/trace.go:171","msg":"trace[934782974] transaction","detail":"{read_only:false; response_revision:5220; number_of_response:1; }","duration":"147.432479ms","start":"2026-08-14T23:21:10.937219Z","end":"2026-08-14T23:21:11.084652Z","steps":["trace[934782974] 'process raft request' (duration: 147.012498ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.084724Z","caller":"traceutil/trace.go:171","msg":"trace[375437928] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"147.465431ms","start":"2026-08-14T23:21:10.937234Z","end":"2026-08-14T23:21:11.084699Z","steps":["trace[375437928] 'process raft request' (duration: 147.038509ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.084666Z","caller":"traceutil/trace.go:171","msg":"trace[1356601309] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"148.914639ms","start":"2026-08-14T23:21:10.93564Z","end":"2026-08-14T23:21:11.084555Z","steps":["trace[1356601309] 'process raft request' (duration: 78.797194ms)","trace[1356601309] 'compare' (duration: 69.589051ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:21:11.477261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.697801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h4cmj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-14T23:21:11.477349Z","caller":"traceutil/trace.go:171","msg":"trace[22276871] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h4cmj; range_end:; response_count:1; response_revision:5222; }","duration":"245.862106ms","start":"2026-08-14T23:21:11.231462Z","end":"2026-08-14T23:21:11.477324Z","steps":["trace[22276871] 'range keys from in-memory index tree' (duration: 245.484356ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.477415Z","caller":"traceutil/trace.go:171","msg":"trace[215181644] linearizableReadLoop","detail":"{readStateIndex:5419; appliedIndex:5418; }","duration":"141.111981ms","start":"2026-08-14T23:21:11.336276Z","end":"2026-08-14T23:21:11.477388Z","steps":["trace[215181644] 'read index received' (duration: 139.996962ms)","trace[215181644] 'applied index is now lower than readState.Index' (duration: 1.113569ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:21:11.477525Z","caller":"traceutil/trace.go:171","msg":"trace[1348265420] transaction","detail":"{read_only:false; response_revision:5223; number_of_response:1; }","duration":"174.319564ms","start":"2026-08-14T23:21:11.303156Z","end":"2026-08-14T23:21:11.477476Z","steps":["trace[1348265420] 'process raft request' (duration: 173.243775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:21:11.477674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.374078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-14T23:21:11.477717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.242214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-14T23:21:11.477748Z","caller":"traceutil/trace.go:171","msg":"trace[1348111741] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5223; }","duration":"141.480891ms","start":"2026-08-14T23:21:11.336244Z","end":"2026-08-14T23:21:11.477725Z","steps":["trace[1348111741] 'agreement among raft nodes before linearized reading' (duration: 141.302257ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.477794Z","caller":"traceutil/trace.go:171","msg":"trace[796043224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5223; }","duration":"141.408019ms","start":"2026-08-14T23:21:11.336364Z","end":"2026-08-14T23:21:11.477772Z","steps":["trace[796043224] 'agreement among raft nodes before linearized reading' (duration: 141.191293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:21:11.795153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.323008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:21:11.795338Z","caller":"traceutil/trace.go:171","msg":"trace[1479755734] linearizableReadLoop","detail":"{readStateIndex:5421; appliedIndex:5419; }","duration":"312.662932ms","start":"2026-08-14T23:21:11.482656Z","end":"2026-08-14T23:21:11.795319Z","steps":["trace[1479755734] 'read index received' (duration: 112.83829ms)","trace[1479755734] 'applied index is now lower than readState.Index' (duration: 199.823592ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:21:11.795429Z","caller":"traceutil/trace.go:171","msg":"trace[797052210] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"313.186626ms","start":"2026-08-14T23:21:11.48223Z","end":"2026-08-14T23:21:11.795416Z","steps":["trace[797052210] 'process raft request' (duration: 113.345613ms)","trace[797052210] 'compare' (duration: 199.185645ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:21:11.795496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:21:11.482207Z","time spent":"313.256378ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:21:11.795658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.425727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:21:11.795688Z","caller":"traceutil/trace.go:171","msg":"trace[1647373810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5225; }","duration":"212.476948ms","start":"2026-08-14T23:21:11.583202Z","end":"2026-08-14T23:21:11.795679Z","steps":["trace[1647373810] 'agreement among raft nodes before linearized reading' (duration: 212.425127ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:11.795826Z","caller":"traceutil/trace.go:171","msg":"trace[601978768] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"313.493073ms","start":"2026-08-14T23:21:11.482298Z","end":"2026-08-14T23:21:11.795791Z","steps":["trace[601978768] 'process raft request' (duration: 312.962869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:21:11.796008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:21:11.482273Z","time spent":"313.638928ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:21:11.796013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.296818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70917"} {"level":"info","ts":"2026-08-14T23:21:11.796177Z","caller":"traceutil/trace.go:171","msg":"trace[1950269764] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5225; }","duration":"313.509604ms","start":"2026-08-14T23:21:11.482614Z","end":"2026-08-14T23:21:11.796124Z","steps":["trace[1950269764] 'agreement among raft nodes before linearized reading' (duration: 312.906978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:21:11.796239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:21:11.482601Z","time spent":"313.620997ms","remote":"127.0.0.1:48592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":70939,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-14T23:21:18.770321Z","caller":"traceutil/trace.go:171","msg":"trace[1165178199] linearizableReadLoop","detail":"{readStateIndex:5612; appliedIndex:5610; }","duration":"127.261854ms","start":"2026-08-14T23:21:18.64303Z","end":"2026-08-14T23:21:18.770292Z","steps":["trace[1165178199] 'read index received' (duration: 10.003386ms)","trace[1165178199] 'applied index is now lower than readState.Index' (duration: 117.257188ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:21:18.770512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.543095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-14T23:21:18.770521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.466509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:21:18.770554Z","caller":"traceutil/trace.go:171","msg":"trace[1389927845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5411; }","duration":"123.629667ms","start":"2026-08-14T23:21:18.646913Z","end":"2026-08-14T23:21:18.770543Z","steps":["trace[1389927845] 'agreement among raft nodes before linearized reading' (duration: 123.540985ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:18.770606Z","caller":"traceutil/trace.go:171","msg":"trace[1186722540] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5411; }","duration":"127.617053ms","start":"2026-08-14T23:21:18.642972Z","end":"2026-08-14T23:21:18.770589Z","steps":["trace[1186722540] 'agreement among raft nodes before linearized reading' (duration: 127.473849ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:21:18.771026Z","caller":"traceutil/trace.go:171","msg":"trace[964335113] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"135.77851ms","start":"2026-08-14T23:21:18.634612Z","end":"2026-08-14T23:21:18.77039Z","steps":["trace[964335113] 'process raft request' (duration: 117.061443ms)","trace[964335113] 'compare' (duration: 18.488451ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:22:43.500752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-08-14T23:22:43.583159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"80.840565ms","hash":160854012,"current-db-size-bytes":57966592,"current-db-size":"58 MB","current-db-size-in-use-bytes":32190464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-14T23:22:43.58325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":160854012,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-08-14T23:27:19.976903Z","caller":"traceutil/trace.go:171","msg":"trace[344021348] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"157.064847ms","start":"2026-08-14T23:27:19.819807Z","end":"2026-08-14T23:27:19.976872Z","steps":["trace[344021348] 'process raft request' (duration: 156.832101ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:27:20.006963Z","caller":"traceutil/trace.go:171","msg":"trace[1504944371] linearizableReadLoop","detail":"{readStateIndex:8478; appliedIndex:8476; }","duration":"107.416439ms","start":"2026-08-14T23:27:19.89952Z","end":"2026-08-14T23:27:20.006936Z","steps":["trace[1504944371] 'read index received' (duration: 77.231235ms)","trace[1504944371] 'applied index is now lower than readState.Index' (duration: 30.183414ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:27:20.007263Z","caller":"traceutil/trace.go:171","msg":"trace[749964201] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"134.206514ms","start":"2026-08-14T23:27:19.873028Z","end":"2026-08-14T23:27:20.007235Z","steps":["trace[749964201] 'process raft request' (duration: 133.753272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:27:20.007425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.878661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h4cmj\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-14T23:27:20.007486Z","caller":"traceutil/trace.go:171","msg":"trace[109790549] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h4cmj; range_end:; response_count:1; response_revision:8192; }","duration":"108.006724ms","start":"2026-08-14T23:27:19.899464Z","end":"2026-08-14T23:27:20.007471Z","steps":["trace[109790549] 'agreement among raft nodes before linearized reading' (duration: 107.753548ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:27:43.507706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6147} {"level":"info","ts":"2026-08-14T23:27:43.613561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6147,"took":"103.884706ms","hash":3640054438,"current-db-size-bytes":57966592,"current-db-size":"58 MB","current-db-size-in-use-bytes":23187456,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-14T23:27:43.613632Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3640054438,"revision":6147,"compact-revision":3009} {"level":"info","ts":"2026-08-14T23:27:48.564149Z","caller":"traceutil/trace.go:171","msg":"trace[1615066445] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"317.126852ms","start":"2026-08-14T23:27:48.246985Z","end":"2026-08-14T23:27:48.564112Z","steps":["trace[1615066445] 'process raft request' (duration: 257.377038ms)","trace[1615066445] 'compare' (duration: 59.523298ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:27:48.564319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:27:48.246968Z","time spent":"317.274745ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:28:00.995098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.040431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6sr4t\" ","response":"range_response_count:1 size:4648"} {"level":"info","ts":"2026-08-14T23:28:00.995396Z","caller":"traceutil/trace.go:171","msg":"trace[1718224067] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6sr4t; range_end:; response_count:1; response_revision:8505; }","duration":"113.430612ms","start":"2026-08-14T23:28:00.881927Z","end":"2026-08-14T23:28:00.995358Z","steps":["trace[1718224067] 'range keys from in-memory index tree' (duration: 112.867637ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:28:02.020451Z","caller":"traceutil/trace.go:171","msg":"trace[1791070127] linearizableReadLoop","detail":"{readStateIndex:8813; appliedIndex:8812; }","duration":"185.413608ms","start":"2026-08-14T23:28:01.835014Z","end":"2026-08-14T23:28:02.020427Z","steps":["trace[1791070127] 'read index received' (duration: 185.230753ms)","trace[1791070127] 'applied index is now lower than readState.Index' (duration: 181.605µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:28:02.020525Z","caller":"traceutil/trace.go:171","msg":"trace[1154335674] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"194.016943ms","start":"2026-08-14T23:28:01.826487Z","end":"2026-08-14T23:28:02.020504Z","steps":["trace[1154335674] 'process raft request' (duration: 193.713195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:28:02.020739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.716956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7f645d5466-x99rc.18cbcfada1ad42d4\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-08-14T23:28:02.020781Z","caller":"traceutil/trace.go:171","msg":"trace[1129259736] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7f645d5466-x99rc.18cbcfada1ad42d4; range_end:; response_count:1; response_revision:8512; }","duration":"185.812058ms","start":"2026-08-14T23:28:01.834955Z","end":"2026-08-14T23:28:02.020767Z","steps":["trace[1129259736] 'agreement among raft nodes before linearized reading' (duration: 185.591673ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:28:02.08078Z","caller":"traceutil/trace.go:171","msg":"trace[435739216] transaction","detail":"{read_only:false; response_revision:8514; number_of_response:1; }","duration":"176.70759ms","start":"2026-08-14T23:28:01.904046Z","end":"2026-08-14T23:28:02.080754Z","steps":["trace[435739216] 'process raft request' (duration: 176.622688ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:28:02.080796Z","caller":"traceutil/trace.go:171","msg":"trace[725299384] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"178.054174ms","start":"2026-08-14T23:28:01.902711Z","end":"2026-08-14T23:28:02.080766Z","steps":["trace[725299384] 'process raft request' (duration: 175.204459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:28:02.097654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.234829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-14T23:28:02.097755Z","caller":"traceutil/trace.go:171","msg":"trace[1902751744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8515; }","duration":"115.422404ms","start":"2026-08-14T23:28:01.982308Z","end":"2026-08-14T23:28:02.097731Z","steps":["trace[1902751744] 'agreement among raft nodes before linearized reading' (duration: 115.153726ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:28:45.470696Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000240c.wal"} {"level":"info","ts":"2026-08-14T23:29:13.689492Z","caller":"traceutil/trace.go:171","msg":"trace[1983589741] linearizableReadLoop","detail":"{readStateIndex:9494; appliedIndex:9493; }","duration":"142.904563ms","start":"2026-08-14T23:29:13.546563Z","end":"2026-08-14T23:29:13.689467Z","steps":["trace[1983589741] 'read index received' (duration: 76.614737ms)","trace[1983589741] 'applied index is now lower than readState.Index' (duration: 66.288436ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:29:13.689583Z","caller":"traceutil/trace.go:171","msg":"trace[968513778] transaction","detail":"{read_only:false; response_revision:9172; number_of_response:1; }","duration":"197.204176ms","start":"2026-08-14T23:29:13.492333Z","end":"2026-08-14T23:29:13.689537Z","steps":["trace[968513778] 'process raft request' (duration: 130.867448ms)","trace[968513778] 'compare' (duration: 66.02913ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:29:13.689695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.112959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:29:13.689777Z","caller":"traceutil/trace.go:171","msg":"trace[1430066867] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:9172; }","duration":"143.210692ms","start":"2026-08-14T23:29:13.546548Z","end":"2026-08-14T23:29:13.689759Z","steps":["trace[1430066867] 'agreement among raft nodes before linearized reading' (duration: 143.073328ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:29:54.0645Z","caller":"traceutil/trace.go:171","msg":"trace[336636432] linearizableReadLoop","detail":"{readStateIndex:9874; appliedIndex:9873; }","duration":"142.818821ms","start":"2026-08-14T23:29:53.921651Z","end":"2026-08-14T23:29:54.06447Z","steps":["trace[336636432] 'read index received' (duration: 142.486943ms)","trace[336636432] 'applied index is now lower than readState.Index' (duration: 330.828µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:29:54.064719Z","caller":"traceutil/trace.go:171","msg":"trace[697083121] transaction","detail":"{read_only:false; response_revision:9543; number_of_response:1; }","duration":"151.280773ms","start":"2026-08-14T23:29:53.91336Z","end":"2026-08-14T23:29:54.064641Z","steps":["trace[697083121] 'process raft request' (duration: 150.751849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:54.06492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.438941ms","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-14T23:29:54.065003Z","caller":"traceutil/trace.go:171","msg":"trace[443874938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9543; }","duration":"142.547364ms","start":"2026-08-14T23:29:53.922437Z","end":"2026-08-14T23:29:54.064984Z","steps":["trace[443874938] 'agreement among raft nodes before linearized reading' (duration: 142.268737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:29:54.06526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.585551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-14T23:29:54.065309Z","caller":"traceutil/trace.go:171","msg":"trace[555084259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9543; }","duration":"143.662893ms","start":"2026-08-14T23:29:53.921634Z","end":"2026-08-14T23:29:54.065297Z","steps":["trace[555084259] 'agreement among raft nodes before linearized reading' (duration: 143.494439ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:30:06.961453Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T23:30:06.966218Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-14T23:30:06.966324Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-14T23:32:43.513745Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8325} {"level":"info","ts":"2026-08-14T23:32:43.589854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8325,"took":"73.825652ms","hash":2321951826,"current-db-size-bytes":57966592,"current-db-size":"58 MB","current-db-size-in-use-bytes":27029504,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-14T23:32:43.589914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2321951826,"revision":8325,"compact-revision":6147} {"level":"warn","ts":"2026-08-14T23:32:49.133487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.492521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:32:49.133588Z","caller":"traceutil/trace.go:171","msg":"trace[1366362949] linearizableReadLoop","detail":"{readStateIndex:11452; appliedIndex:11451; }","duration":"189.503774ms","start":"2026-08-14T23:32:48.944069Z","end":"2026-08-14T23:32:49.133573Z","steps":["trace[1366362949] 'read index received' (duration: 55.838738ms)","trace[1366362949] 'applied index is now lower than readState.Index' (duration: 133.663796ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:32:49.133833Z","caller":"traceutil/trace.go:171","msg":"trace[231277591] transaction","detail":"{read_only:false; response_revision:11073; number_of_response:1; }","duration":"226.097316ms","start":"2026-08-14T23:32:48.907721Z","end":"2026-08-14T23:32:49.133819Z","steps":["trace[231277591] 'process raft request' (duration: 92.192785ms)","trace[231277591] 'compare' (duration: 133.381977ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:32:49.13408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.989576ms","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-14T23:32:49.134135Z","caller":"traceutil/trace.go:171","msg":"trace[632492626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11073; }","duration":"190.082629ms","start":"2026-08-14T23:32:48.944038Z","end":"2026-08-14T23:32:49.134121Z","steps":["trace[632492626] 'agreement among raft nodes before linearized reading' (duration: 189.906174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:32:49.134361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.752204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-14T23:32:49.134437Z","caller":"traceutil/trace.go:171","msg":"trace[781786465] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11073; }","duration":"180.860576ms","start":"2026-08-14T23:32:48.953558Z","end":"2026-08-14T23:32:49.134419Z","steps":["trace[781786465] 'agreement among raft nodes before linearized reading' (duration: 180.667101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:32:49.134345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.214452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-14T23:32:49.134546Z","caller":"traceutil/trace.go:171","msg":"trace[1051949699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11073; }","duration":"190.456688ms","start":"2026-08-14T23:32:48.944057Z","end":"2026-08-14T23:32:49.134514Z","steps":["trace[1051949699] 'agreement among raft nodes before linearized reading' (duration: 190.038107ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:37:43.520225Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11045} {"level":"info","ts":"2026-08-14T23:37:43.608973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11045,"took":"85.297356ms","hash":1009518688,"current-db-size-bytes":57966592,"current-db-size":"58 MB","current-db-size-in-use-bytes":27967488,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-14T23:37:43.609036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1009518688,"revision":11045,"compact-revision":8325} {"level":"warn","ts":"2026-08-14T23:39:02.721189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.857148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T23:39:02.721288Z","caller":"traceutil/trace.go:171","msg":"trace[245943036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13592; }","duration":"137.998842ms","start":"2026-08-14T23:39:02.58327Z","end":"2026-08-14T23:39:02.721269Z","steps":["trace[245943036] 'range keys from in-memory index tree' (duration: 137.751605ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:39:11.186888Z","caller":"traceutil/trace.go:171","msg":"trace[281233195] transaction","detail":"{read_only:false; response_revision:13652; number_of_response:1; }","duration":"262.640735ms","start":"2026-08-14T23:39:10.924206Z","end":"2026-08-14T23:39:11.186847Z","steps":["trace[281233195] 'process raft request' (duration: 262.353697ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:39:11.316777Z","caller":"traceutil/trace.go:171","msg":"trace[812843274] transaction","detail":"{read_only:false; response_revision:13654; number_of_response:1; }","duration":"125.028474ms","start":"2026-08-14T23:39:11.191732Z","end":"2026-08-14T23:39:11.316761Z","steps":["trace[812843274] 'process raft request' (duration: 124.836649ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:39:11.316694Z","caller":"traceutil/trace.go:171","msg":"trace[1410380100] transaction","detail":"{read_only:false; response_revision:13653; number_of_response:1; }","duration":"124.970772ms","start":"2026-08-14T23:39:11.191668Z","end":"2026-08-14T23:39:11.316639Z","steps":["trace[1410380100] 'process raft request' (duration: 109.60169ms)","trace[1410380100] 'compare' (duration: 15.149556ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:39:20.012547Z","caller":"traceutil/trace.go:171","msg":"trace[1269773109] linearizableReadLoop","detail":"{readStateIndex:14282; appliedIndex:14280; }","duration":"229.577003ms","start":"2026-08-14T23:39:19.782946Z","end":"2026-08-14T23:39:20.012523Z","steps":["trace[1269773109] 'read index received' (duration: 2.139836ms)","trace[1269773109] 'applied index is now lower than readState.Index' (duration: 227.436147ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:39:20.012593Z","caller":"traceutil/trace.go:171","msg":"trace[2051455502] transaction","detail":"{read_only:false; response_revision:13809; number_of_response:1; }","duration":"234.765398ms","start":"2026-08-14T23:39:19.777797Z","end":"2026-08-14T23:39:20.012562Z","steps":["trace[2051455502] 'process raft request' (duration: 234.557422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:39:20.012798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.832939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-012a9d92-dce8-4e3c-801b-8fec7b97113d\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-08-14T23:39:20.012844Z","caller":"traceutil/trace.go:171","msg":"trace[2063843203] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-012a9d92-dce8-4e3c-801b-8fec7b97113d; range_end:; response_count:1; response_revision:13809; }","duration":"229.932492ms","start":"2026-08-14T23:39:19.782899Z","end":"2026-08-14T23:39:20.012832Z","steps":["trace[2063843203] 'agreement among raft nodes before linearized reading' (duration: 229.771627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:39:20.012952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.625045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ecd1c0e6-53f9-4bf8-bacb-1ae4d938f5be\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-08-14T23:39:20.013069Z","caller":"traceutil/trace.go:171","msg":"trace[1085436771] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ecd1c0e6-53f9-4bf8-bacb-1ae4d938f5be; range_end:; response_count:1; response_revision:13809; }","duration":"221.81784ms","start":"2026-08-14T23:39:19.79123Z","end":"2026-08-14T23:39:20.013048Z","steps":["trace[1085436771] 'agreement among raft nodes before linearized reading' (duration: 221.491992ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:39:20.237871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.276039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T23:39:20.238083Z","caller":"traceutil/trace.go:171","msg":"trace[1248931005] linearizableReadLoop","detail":"{readStateIndex:14284; appliedIndex:14282; }","duration":"197.859724ms","start":"2026-08-14T23:39:20.040206Z","end":"2026-08-14T23:39:20.238066Z","steps":["trace[1248931005] 'read index received' (duration: 64.295298ms)","trace[1248931005] 'applied index is now lower than readState.Index' (duration: 133.563316ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:39:20.238186Z","caller":"traceutil/trace.go:171","msg":"trace[1306134558] transaction","detail":"{read_only:false; response_revision:13811; number_of_response:1; }","duration":"217.304781ms","start":"2026-08-14T23:39:20.020871Z","end":"2026-08-14T23:39:20.238176Z","steps":["trace[1306134558] 'process raft request' (duration: 217.137247ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:39:20.238221Z","caller":"traceutil/trace.go:171","msg":"trace[1799965414] transaction","detail":"{read_only:false; response_revision:13810; number_of_response:1; }","duration":"219.900579ms","start":"2026-08-14T23:39:20.018285Z","end":"2026-08-14T23:39:20.238185Z","steps":["trace[1799965414] 'process raft request' (duration: 86.224421ms)","trace[1799965414] 'compare' (duration: 133.174715ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T23:39:20.238496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.769886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-012a9d92-dce8-4e3c-801b-8fec7b97113d\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-08-14T23:39:20.238603Z","caller":"traceutil/trace.go:171","msg":"trace[204556578] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-012a9d92-dce8-4e3c-801b-8fec7b97113d; range_end:; response_count:1; response_revision:13811; }","duration":"165.888279ms","start":"2026-08-14T23:39:20.072684Z","end":"2026-08-14T23:39:20.238572Z","steps":["trace[204556578] 'agreement among raft nodes before linearized reading' (duration: 165.594622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:39:20.239735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.503657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-08-14T23:39:20.239833Z","caller":"traceutil/trace.go:171","msg":"trace[283197325] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:13811; }","duration":"199.646041ms","start":"2026-08-14T23:39:20.040165Z","end":"2026-08-14T23:39:20.239812Z","steps":["trace[283197325] 'agreement among raft nodes before linearized reading' (duration: 198.172572ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:42:43.526018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13075} {"level":"info","ts":"2026-08-14T23:42:43.60664Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13075,"took":"78.278812ms","hash":3731187351,"current-db-size-bytes":57966592,"current-db-size":"58 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-14T23:42:43.60669Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3731187351,"revision":13075,"compact-revision":11045} {"level":"info","ts":"2026-08-14T23:46:44.356175Z","caller":"traceutil/trace.go:171","msg":"trace[1639978883] transaction","detail":"{read_only:false; response_revision:17645; number_of_response:1; }","duration":"145.845355ms","start":"2026-08-14T23:46:44.21029Z","end":"2026-08-14T23:46:44.356135Z","steps":["trace[1639978883] 'process raft request' (duration: 145.65496ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:46:44.616289Z","caller":"traceutil/trace.go:171","msg":"trace[1333746634] transaction","detail":"{read_only:false; response_revision:17646; number_of_response:1; }","duration":"253.684378ms","start":"2026-08-14T23:46:44.362581Z","end":"2026-08-14T23:46:44.616265Z","steps":["trace[1333746634] 'process raft request' (duration: 253.549695ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:46:44.830136Z","caller":"traceutil/trace.go:171","msg":"trace[99188792] linearizableReadLoop","detail":"{readStateIndex:18238; appliedIndex:18236; }","duration":"398.563278ms","start":"2026-08-14T23:46:44.431544Z","end":"2026-08-14T23:46:44.830107Z","steps":["trace[99188792] 'read index received' (duration: 184.535195ms)","trace[99188792] 'applied index is now lower than readState.Index' (duration: 214.027233ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:46:44.830256Z","caller":"traceutil/trace.go:171","msg":"trace[481824507] transaction","detail":"{read_only:false; response_revision:17647; number_of_response:1; }","duration":"448.927462ms","start":"2026-08-14T23:46:44.381292Z","end":"2026-08-14T23:46:44.830219Z","steps":["trace[481824507] 'process raft request' (duration: 448.625685ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:46:44.830415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.841636ms","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-14T23:46:44.830465Z","caller":"traceutil/trace.go:171","msg":"trace[1906258463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17647; }","duration":"398.939907ms","start":"2026-08-14T23:46:44.431513Z","end":"2026-08-14T23:46:44.830452Z","steps":["trace[1906258463] 'agreement among raft nodes before linearized reading' (duration: 398.757523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:46:44.830477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.317451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-14T23:46:44.830504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:46:44.431499Z","time spent":"398.99634ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-14T23:46:44.830498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:46:44.381267Z","time spent":"449.077116ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T23:46:44.830548Z","caller":"traceutil/trace.go:171","msg":"trace[563119609] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17647; }","duration":"365.425853ms","start":"2026-08-14T23:46:44.465102Z","end":"2026-08-14T23:46:44.830528Z","steps":["trace[563119609] 'agreement among raft nodes before linearized reading' (duration: 365.234788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:46:44.830446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.474007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-14T23:46:44.830592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:46:44.46508Z","time spent":"365.502376ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-14T23:46:44.83063Z","caller":"traceutil/trace.go:171","msg":"trace[546976019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17647; }","duration":"247.672511ms","start":"2026-08-14T23:46:44.582913Z","end":"2026-08-14T23:46:44.830586Z","steps":["trace[546976019] 'agreement among raft nodes before linearized reading' (duration: 247.450125ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:47:43.532389Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15641} {"level":"info","ts":"2026-08-14T23:47:43.632772Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15641,"took":"96.891438ms","hash":3016748027,"current-db-size-bytes":57966592,"current-db-size":"58 MB","current-db-size-in-use-bytes":33587200,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-14T23:47:43.632895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3016748027,"revision":15641,"compact-revision":13075} {"level":"info","ts":"2026-08-14T23:50:15.250023Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-14T23:50:15.255188Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-14T23:50:15.255339Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-14T23:51:14.196062Z","caller":"traceutil/trace.go:171","msg":"trace[2105294036] transaction","detail":"{read_only:false; response_revision:19673; number_of_response:1; }","duration":"191.092016ms","start":"2026-08-14T23:51:14.004939Z","end":"2026-08-14T23:51:14.196031Z","steps":["trace[2105294036] 'process raft request' (duration: 97.113764ms)","trace[2105294036] 'compare' (duration: 93.498319ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:51:14.506475Z","caller":"traceutil/trace.go:171","msg":"trace[1386718986] linearizableReadLoop","detail":"{readStateIndex:20336; appliedIndex:20335; }","duration":"236.082718ms","start":"2026-08-14T23:51:14.270365Z","end":"2026-08-14T23:51:14.506448Z","steps":["trace[1386718986] 'read index received' (duration: 235.896163ms)","trace[1386718986] 'applied index is now lower than readState.Index' (duration: 184.575µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:51:14.506495Z","caller":"traceutil/trace.go:171","msg":"trace[1730276380] transaction","detail":"{read_only:false; response_revision:19675; number_of_response:1; }","duration":"304.335418ms","start":"2026-08-14T23:51:14.202127Z","end":"2026-08-14T23:51:14.506462Z","steps":["trace[1730276380] 'process raft request' (duration: 304.144793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:51:14.506667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T23:51:14.202104Z","time spent":"304.476542ms","remote":"127.0.0.1:48678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T23:51:14.506746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.357875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-14T23:51:14.506808Z","caller":"traceutil/trace.go:171","msg":"trace[280883207] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19675; }","duration":"236.467218ms","start":"2026-08-14T23:51:14.270324Z","end":"2026-08-14T23:51:14.506791Z","steps":["trace[280883207] 'agreement among raft nodes before linearized reading' (duration: 236.225872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:51:14.786927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.196242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-14T23:51:14.787125Z","caller":"traceutil/trace.go:171","msg":"trace[1442753147] linearizableReadLoop","detail":"{readStateIndex:20338; appliedIndex:20336; }","duration":"129.673353ms","start":"2026-08-14T23:51:14.657436Z","end":"2026-08-14T23:51:14.787109Z","steps":["trace[1442753147] 'read index received' (duration: 38.731µs)","trace[1442753147] 'applied index is now lower than readState.Index' (duration: 129.633752ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:51:14.787227Z","caller":"traceutil/trace.go:171","msg":"trace[2043173517] transaction","detail":"{read_only:false; response_revision:19676; number_of_response:1; }","duration":"276.150875ms","start":"2026-08-14T23:51:14.511021Z","end":"2026-08-14T23:51:14.787172Z","steps":["trace[2043173517] 'process raft request' (duration: 126.626504ms)","trace[2043173517] 'compare' (duration: 149.081259ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T23:51:14.787294Z","caller":"traceutil/trace.go:171","msg":"trace[658799358] transaction","detail":"{read_only:false; response_revision:19677; number_of_response:1; }","duration":"247.24742ms","start":"2026-08-14T23:51:14.540012Z","end":"2026-08-14T23:51:14.78726Z","steps":["trace[658799358] 'process raft request' (duration: 247.041165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T23:51:14.787556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.121474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:26 size:233658"} {"level":"info","ts":"2026-08-14T23:51:14.787601Z","caller":"traceutil/trace.go:171","msg":"trace[867490057] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:26; response_revision:19677; }","duration":"130.166306ms","start":"2026-08-14T23:51:14.657422Z","end":"2026-08-14T23:51:14.787588Z","steps":["trace[867490057] 'agreement among raft nodes before linearized reading' (duration: 129.741955ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:51:15.384667Z","caller":"traceutil/trace.go:171","msg":"trace[155723434] transaction","detail":"{read_only:false; response_revision:19680; number_of_response:1; }","duration":"118.388128ms","start":"2026-08-14T23:51:15.266259Z","end":"2026-08-14T23:51:15.384647Z","steps":["trace[155723434] 'process raft request' (duration: 118.280865ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:51:15.498479Z","caller":"traceutil/trace.go:171","msg":"trace[1295240760] transaction","detail":"{read_only:false; response_revision:19681; number_of_response:1; }","duration":"137.746104ms","start":"2026-08-14T23:51:15.360705Z","end":"2026-08-14T23:51:15.498451Z","steps":["trace[1295240760] 'process raft request' (duration: 136.860991ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:52:43.540003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18155} {"level":"info","ts":"2026-08-14T23:52:43.653061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18155,"took":"110.315098ms","hash":3149623549,"current-db-size-bytes":57966592,"current-db-size":"58 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-14T23:52:43.653114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3149623549,"revision":18155,"compact-revision":15641} {"level":"info","ts":"2026-08-14T23:54:54.392199Z","caller":"traceutil/trace.go:171","msg":"trace[1052498894] transaction","detail":"{read_only:false; response_revision:22012; number_of_response:1; }","duration":"192.249395ms","start":"2026-08-14T23:54:54.199925Z","end":"2026-08-14T23:54:54.392174Z","steps":["trace[1052498894] 'process raft request' (duration: 192.07009ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:54.398794Z","caller":"traceutil/trace.go:171","msg":"trace[1718075663] transaction","detail":"{read_only:false; response_revision:22014; number_of_response:1; }","duration":"123.267865ms","start":"2026-08-14T23:54:54.275516Z","end":"2026-08-14T23:54:54.398784Z","steps":["trace[1718075663] 'process raft request' (duration: 123.179263ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:54:54.399056Z","caller":"traceutil/trace.go:171","msg":"trace[899443863] transaction","detail":"{read_only:false; response_revision:22013; number_of_response:1; }","duration":"197.657647ms","start":"2026-08-14T23:54:54.201335Z","end":"2026-08-14T23:54:54.398993Z","steps":["trace[899443863] 'process raft request' (duration: 197.214745ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T23:57:43.546006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20455} {"level":"info","ts":"2026-08-14T23:57:43.655035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20455,"took":"104.682121ms","hash":2163972447,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":43626496,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-14T23:57:43.655091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2163972447,"revision":20455,"compact-revision":18155} {"level":"info","ts":"2026-08-15T00:02:43.553558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23584} {"level":"info","ts":"2026-08-15T00:02:43.69797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23584,"took":"139.90209ms","hash":169728408,"current-db-size-bytes":58388480,"current-db-size":"58 MB","current-db-size-in-use-bytes":31268864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-15T00:02:43.698068Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":169728408,"revision":23584,"compact-revision":20455}