{"level":"warn","ts":"2026-04-20T05:51:41.842494Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:51:41.842689Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-04-20T05:51:41.842801Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T05:51:41.842856Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-20T05:51:41.842887Z","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-04-20T05:51:41.843832Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-04-20T05:51:41.843962Z","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.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-04-20T05:51:41.848478Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.178465ms"} {"level":"info","ts":"2026-04-20T05:51:41.857849Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-04-20T05:51:41.85802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T05:51:41.858229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-04-20T05:51:41.858249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T05:51:41.858267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-04-20T05:51:41.858375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-04-20T05:51:41.875287Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T05:51:41.878519Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T05:51:41.880332Z","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-04-20T05:51:41.882777Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T05:51:41.882939Z","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-04-20T05:51:41.883061Z","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-04-20T05:51:41.883079Z","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-04-20T05:51:41.883523Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T05:51:41.884178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-04-20T05:51:41.88433Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-20T05:51:41.885945Z","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-04-20T05:51:41.886059Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-20T05:51:41.886182Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-20T05:51:41.886246Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T05:51:41.886335Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T05:51:42.358882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T05:51:42.358931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T05:51:42.358958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-04-20T05:51:42.358969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-04-20T05:51:42.358975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-20T05:51:42.358984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-04-20T05:51:42.358991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-20T05:51:42.362456Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:51:42.375079Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T05:51:42.375306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:51:42.375414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:51:42.378419Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T05:51:42.379557Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T05:51:42.379605Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T05:51:42.383233Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-04-20T05:51:42.391067Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:51:42.39124Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:51:42.391273Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:52:13.144444Z","caller":"traceutil/trace.go:171","msg":"trace[919118168] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"161.249243ms","start":"2026-04-20T05:52:12.98319Z","end":"2026-04-20T05:52:13.144439Z","steps":["trace[919118168] 'process raft request' (duration: 161.08026ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.144423Z","caller":"traceutil/trace.go:171","msg":"trace[170634012] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"163.581485ms","start":"2026-04-20T05:52:12.98079Z","end":"2026-04-20T05:52:13.144371Z","steps":["trace[170634012] 'process raft request' (duration: 163.402281ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.144535Z","caller":"traceutil/trace.go:171","msg":"trace[1933324473] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"153.592919ms","start":"2026-04-20T05:52:12.990895Z","end":"2026-04-20T05:52:13.144488Z","steps":["trace[1933324473] 'process raft request' (duration: 153.437966ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.144433Z","caller":"traceutil/trace.go:171","msg":"trace[720587208] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"151.038071ms","start":"2026-04-20T05:52:12.993373Z","end":"2026-04-20T05:52:13.144411Z","steps":["trace[720587208] 'process raft request' (duration: 151.013771ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.144665Z","caller":"traceutil/trace.go:171","msg":"trace[1934106119] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"153.04984ms","start":"2026-04-20T05:52:12.99155Z","end":"2026-04-20T05:52:13.1446Z","steps":["trace[1934106119] 'process raft request' (duration: 152.812505ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.144757Z","caller":"traceutil/trace.go:171","msg":"trace[1455767980] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"154.985165ms","start":"2026-04-20T05:52:12.98971Z","end":"2026-04-20T05:52:13.144695Z","steps":["trace[1455767980] 'process raft request' (duration: 154.591338ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.158699Z","caller":"traceutil/trace.go:171","msg":"trace[1788136884] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"165.115224ms","start":"2026-04-20T05:52:12.993572Z","end":"2026-04-20T05:52:13.158688Z","steps":["trace[1788136884] 'process raft request' (duration: 164.88665ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.158692Z","caller":"traceutil/trace.go:171","msg":"trace[897870908] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"159.426617ms","start":"2026-04-20T05:52:12.999231Z","end":"2026-04-20T05:52:13.158658Z","steps":["trace[897870908] 'process raft request' (duration: 159.312225ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.160312Z","caller":"traceutil/trace.go:171","msg":"trace[744037880] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"159.441418ms","start":"2026-04-20T05:52:13.000807Z","end":"2026-04-20T05:52:13.160248Z","steps":["trace[744037880] 'process raft request' (duration: 157.822188ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.160902Z","caller":"traceutil/trace.go:171","msg":"trace[2036121034] transaction","detail":"{read_only:false; number_of_response:1; response_revision:481; }","duration":"159.983498ms","start":"2026-04-20T05:52:13.000881Z","end":"2026-04-20T05:52:13.160865Z","steps":["trace[2036121034] 'process raft request' (duration: 157.796718ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.16327Z","caller":"traceutil/trace.go:171","msg":"trace[18966952] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"147.969634ms","start":"2026-04-20T05:52:13.015272Z","end":"2026-04-20T05:52:13.163242Z","steps":["trace[18966952] 'process raft request' (duration: 145.090641ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.163329Z","caller":"traceutil/trace.go:171","msg":"trace[2016020212] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"115.772505ms","start":"2026-04-20T05:52:13.047525Z","end":"2026-04-20T05:52:13.163298Z","steps":["trace[2016020212] 'process raft request' (duration: 115.47982ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.164659Z","caller":"traceutil/trace.go:171","msg":"trace[696134284] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"163.945872ms","start":"2026-04-20T05:52:13.000667Z","end":"2026-04-20T05:52:13.164613Z","steps":["trace[696134284] 'process raft request' (duration: 157.90325ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:13.411783Z","caller":"traceutil/trace.go:171","msg":"trace[266166227] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"100.300977ms","start":"2026-04-20T05:52:13.311443Z","end":"2026-04-20T05:52:13.411744Z","steps":["trace[266166227] 'process raft request' (duration: 61.161209ms)","trace[266166227] 'compare' (duration: 38.938443ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:52:18.800904Z","caller":"traceutil/trace.go:171","msg":"trace[27689516] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"371.892922ms","start":"2026-04-20T05:52:18.428953Z","end":"2026-04-20T05:52:18.800846Z","steps":["trace[27689516] 'process raft request' (duration: 367.062611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:18.80301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:18.428918Z","time spent":"372.083686ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:52:18.810114Z","caller":"traceutil/trace.go:171","msg":"trace[577710081] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"111.681419ms","start":"2026-04-20T05:52:18.698397Z","end":"2026-04-20T05:52:18.810078Z","steps":["trace[577710081] 'read index received' (duration: 102.252273ms)","trace[577710081] 'applied index is now lower than readState.Index' (duration: 9.427906ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:52:18.810204Z","caller":"traceutil/trace.go:171","msg":"trace[246119186] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"221.163156ms","start":"2026-04-20T05:52:18.589015Z","end":"2026-04-20T05:52:18.810178Z","steps":["trace[246119186] 'process raft request' (duration: 220.530555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:18.810382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.977024ms","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-04-20T05:52:18.810443Z","caller":"traceutil/trace.go:171","msg":"trace[957170497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"112.114997ms","start":"2026-04-20T05:52:18.698317Z","end":"2026-04-20T05:52:18.810432Z","steps":["trace[957170497] 'agreement among raft nodes before linearized reading' (duration: 111.873502ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:19.548707Z","caller":"traceutil/trace.go:171","msg":"trace[272548713] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"217.347707ms","start":"2026-04-20T05:52:19.331337Z","end":"2026-04-20T05:52:19.548685Z","steps":["trace[272548713] 'process raft request' (duration: 217.204745ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:19.58241Z","caller":"traceutil/trace.go:171","msg":"trace[644151672] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"245.324077ms","start":"2026-04-20T05:52:19.337058Z","end":"2026-04-20T05:52:19.582382Z","steps":["trace[644151672] 'process raft request' (duration: 238.008049ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:20.21979Z","caller":"traceutil/trace.go:171","msg":"trace[466551721] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"267.989358ms","start":"2026-04-20T05:52:19.951769Z","end":"2026-04-20T05:52:20.219759Z","steps":["trace[466551721] 'read index received' (duration: 267.742144ms)","trace[466551721] 'applied index is now lower than readState.Index' (duration: 246.014µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:52:20.219996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.217982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:52:20.220074Z","caller":"traceutil/trace.go:171","msg":"trace[248583485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"268.349234ms","start":"2026-04-20T05:52:19.951707Z","end":"2026-04-20T05:52:20.220056Z","steps":["trace[248583485] 'agreement among raft nodes before linearized reading' (duration: 268.171731ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:20.219858Z","caller":"traceutil/trace.go:171","msg":"trace[1537355097] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"576.740795ms","start":"2026-04-20T05:52:19.643066Z","end":"2026-04-20T05:52:20.219807Z","steps":["trace[1537355097] 'process raft request' (duration: 576.46711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:20.220294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:19.643041Z","time spent":"577.161683ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T05:52:21.913954Z","caller":"traceutil/trace.go:171","msg":"trace[1887300648] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"320.320673ms","start":"2026-04-20T05:52:21.593602Z","end":"2026-04-20T05:52:21.913923Z","steps":["trace[1887300648] 'read index received' (duration: 309.609093ms)","trace[1887300648] 'applied index is now lower than readState.Index' (duration: 10.71016ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:52:21.914103Z","caller":"traceutil/trace.go:171","msg":"trace[797517785] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"200.642604ms","start":"2026-04-20T05:52:21.71343Z","end":"2026-04-20T05:52:21.914073Z","steps":["trace[797517785] 'process raft request' (duration: 200.446681ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:21.914323Z","caller":"traceutil/trace.go:171","msg":"trace[547601908] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"338.469191ms","start":"2026-04-20T05:52:21.575833Z","end":"2026-04-20T05:52:21.914302Z","steps":["trace[547601908] 'process raft request' (duration: 327.205521ms)","trace[547601908] 'compare' (duration: 10.663209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:52:21.914382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.556926ms","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:5"} {"level":"info","ts":"2026-04-20T05:52:21.914436Z","caller":"traceutil/trace.go:171","msg":"trace[726366227] 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:610; }","duration":"320.637218ms","start":"2026-04-20T05:52:21.59378Z","end":"2026-04-20T05:52:21.914418Z","steps":["trace[726366227] 'agreement among raft nodes before linearized reading' (duration: 320.497435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:21.914474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:21.575818Z","time spent":"338.561472ms","remote":"127.0.0.1:59476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T05:52:21.914506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.157316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:52:21.914495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.284731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:52:21.914591Z","caller":"traceutil/trace.go:171","msg":"trace[1955806954] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:610; }","duration":"293.273738ms","start":"2026-04-20T05:52:21.621284Z","end":"2026-04-20T05:52:21.914558Z","steps":["trace[1955806954] 'agreement among raft nodes before linearized reading' (duration: 293.138356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:21.914634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.117195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:52:21.914641Z","caller":"traceutil/trace.go:171","msg":"trace[724666428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:610; }","duration":"277.464495ms","start":"2026-04-20T05:52:21.63713Z","end":"2026-04-20T05:52:21.914595Z","steps":["trace[724666428] 'agreement among raft nodes before linearized reading' (duration: 277.240161ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:21.914699Z","caller":"traceutil/trace.go:171","msg":"trace[851894302] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:610; }","duration":"293.241297ms","start":"2026-04-20T05:52:21.621439Z","end":"2026-04-20T05:52:21.914681Z","steps":["trace[851894302] 'agreement among raft nodes before linearized reading' (duration: 293.119035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:21.914709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.911955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:52:21.914682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.175588ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:52:21.914737Z","caller":"traceutil/trace.go:171","msg":"trace[1013995952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:610; }","duration":"276.951545ms","start":"2026-04-20T05:52:21.637777Z","end":"2026-04-20T05:52:21.914729Z","steps":["trace[1013995952] 'agreement among raft nodes before linearized reading' (duration: 276.893013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:21.914782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.160129ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:52:21.914491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:21.593775Z","time spent":"320.695949ms","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-20T05:52:21.914888Z","caller":"traceutil/trace.go:171","msg":"trace[1892551606] 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:610; }","duration":"321.271121ms","start":"2026-04-20T05:52:21.593598Z","end":"2026-04-20T05:52:21.914869Z","steps":["trace[1892551606] 'agreement among raft nodes before linearized reading' (duration: 321.119148ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:21.91479Z","caller":"traceutil/trace.go:171","msg":"trace[2001757775] 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:610; }","duration":"320.327053ms","start":"2026-04-20T05:52:21.594434Z","end":"2026-04-20T05:52:21.914761Z","steps":["trace[2001757775] 'agreement among raft nodes before linearized reading' (duration: 320.080487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:21.914923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.618529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T05:52:21.914974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:21.593564Z","time spent":"321.398282ms","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-20T05:52:21.915014Z","caller":"traceutil/trace.go:171","msg":"trace[1186592030] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:610; }","duration":"277.770271ms","start":"2026-04-20T05:52:21.637219Z","end":"2026-04-20T05:52:21.914989Z","steps":["trace[1186592030] 'agreement among raft nodes before linearized reading' (duration: 277.578157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:21.915074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:21.594425Z","time spent":"320.574167ms","remote":"127.0.0.1:37772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-04-20T05:52:23.039445Z","caller":"traceutil/trace.go:171","msg":"trace[1236397870] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"124.682391ms","start":"2026-04-20T05:52:22.914632Z","end":"2026-04-20T05:52:23.039314Z","steps":["trace[1236397870] 'process raft request' (duration: 114.933149ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:52:24.703555Z","caller":"traceutil/trace.go:171","msg":"trace[822588958] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"137.643662ms","start":"2026-04-20T05:52:24.565889Z","end":"2026-04-20T05:52:24.703533Z","steps":["trace[822588958] 'process raft request' (duration: 137.377877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:34.804783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.36509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-htg9c\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-20T05:52:34.804899Z","caller":"traceutil/trace.go:171","msg":"trace[1589600170] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-htg9c; range_end:; response_count:1; response_revision:739; }","duration":"380.526223ms","start":"2026-04-20T05:52:34.424349Z","end":"2026-04-20T05:52:34.804875Z","steps":["trace[1589600170] 'range keys from in-memory index tree' (duration: 380.137846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:52:34.804946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:52:34.424336Z","time spent":"380.598214ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-htg9c\" "} {"level":"info","ts":"2026-04-20T05:53:03.340747Z","caller":"traceutil/trace.go:171","msg":"trace[1684434752] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"276.136031ms","start":"2026-04-20T05:53:03.06457Z","end":"2026-04-20T05:53:03.340706Z","steps":["trace[1684434752] 'read index received' (duration: 275.854726ms)","trace[1684434752] 'applied index is now lower than readState.Index' (duration: 279.735µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:03.340795Z","caller":"traceutil/trace.go:171","msg":"trace[371939304] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"278.230169ms","start":"2026-04-20T05:53:03.062521Z","end":"2026-04-20T05:53:03.340751Z","steps":["trace[371939304] 'process raft request' (duration: 278.000235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:03.341295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.701841ms","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-04-20T05:53:03.341415Z","caller":"traceutil/trace.go:171","msg":"trace[965855642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1140; }","duration":"276.887854ms","start":"2026-04-20T05:53:03.064498Z","end":"2026-04-20T05:53:03.341386Z","steps":["trace[965855642] 'agreement among raft nodes before linearized reading' (duration: 276.347675ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:53:49.011421Z","caller":"traceutil/trace.go:171","msg":"trace[1530384477] linearizableReadLoop","detail":"{readStateIndex:1867; appliedIndex:1866; }","duration":"194.481412ms","start":"2026-04-20T05:53:48.816891Z","end":"2026-04-20T05:53:49.011373Z","steps":["trace[1530384477] 'read index received' (duration: 194.205696ms)","trace[1530384477] 'applied index is now lower than readState.Index' (duration: 274.486µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:49.011512Z","caller":"traceutil/trace.go:171","msg":"trace[1944493870] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"200.935703ms","start":"2026-04-20T05:53:48.810525Z","end":"2026-04-20T05:53:49.01146Z","steps":["trace[1944493870] 'process raft request' (duration: 200.665937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:49.011762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.840388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T05:53:49.01185Z","caller":"traceutil/trace.go:171","msg":"trace[663968562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1798; }","duration":"194.988121ms","start":"2026-04-20T05:53:48.816845Z","end":"2026-04-20T05:53:49.011833Z","steps":["trace[663968562] 'agreement among raft nodes before linearized reading' (duration: 194.709966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:49.011891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.114781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8796f90b-4fd5-48ac-8f5a-be93a57ebb66\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-20T05:53:49.011942Z","caller":"traceutil/trace.go:171","msg":"trace[2003008622] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8796f90b-4fd5-48ac-8f5a-be93a57ebb66; range_end:; response_count:1; response_revision:1798; }","duration":"181.179053ms","start":"2026-04-20T05:53:48.83075Z","end":"2026-04-20T05:53:49.011929Z","steps":["trace[2003008622] 'agreement among raft nodes before linearized reading' (duration: 180.820246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:49.371273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.62246ms","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-04-20T05:53:49.371373Z","caller":"traceutil/trace.go:171","msg":"trace[1539188039] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1799; }","duration":"151.784053ms","start":"2026-04-20T05:53:49.219563Z","end":"2026-04-20T05:53:49.371347Z","steps":["trace[1539188039] 'range keys from in-memory index tree' (duration: 151.328904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:49.371433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.005701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:53:49.371529Z","caller":"traceutil/trace.go:171","msg":"trace[1492289975] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1799; }","duration":"148.195415ms","start":"2026-04-20T05:53:49.223312Z","end":"2026-04-20T05:53:49.371507Z","steps":["trace[1492289975] 'count revisions from in-memory index tree' (duration: 147.88849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:53:58.469723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.684047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:53:58.469855Z","caller":"traceutil/trace.go:171","msg":"trace[218126282] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"238.6866ms","start":"2026-04-20T05:53:58.231152Z","end":"2026-04-20T05:53:58.469838Z","steps":["trace[218126282] 'read index received' (duration: 91.362142ms)","trace[218126282] 'applied index is now lower than readState.Index' (duration: 147.323198ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:53:58.4699Z","caller":"traceutil/trace.go:171","msg":"trace[1061920534] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"249.245956ms","start":"2026-04-20T05:53:58.220622Z","end":"2026-04-20T05:53:58.469868Z","steps":["trace[1061920534] 'process raft request' (duration: 101.986359ms)","trace[1061920534] 'compare' (duration: 146.517223ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:53:58.469942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.783211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:53:58.469974Z","caller":"traceutil/trace.go:171","msg":"trace[445352052] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1892; }","duration":"238.819182ms","start":"2026-04-20T05:53:58.231147Z","end":"2026-04-20T05:53:58.469966Z","steps":["trace[445352052] 'agreement among raft nodes before linearized reading' (duration: 238.738001ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:54:03.761837Z","caller":"traceutil/trace.go:171","msg":"trace[1408998115] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1991; }","duration":"105.202311ms","start":"2026-04-20T05:54:03.656603Z","end":"2026-04-20T05:54:03.761805Z","steps":["trace[1408998115] 'read index received' (duration: 104.949626ms)","trace[1408998115] 'applied index is now lower than readState.Index' (duration: 251.465µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:54:03.761949Z","caller":"traceutil/trace.go:171","msg":"trace[1396259985] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"105.356403ms","start":"2026-04-20T05:54:03.656562Z","end":"2026-04-20T05:54:03.761919Z","steps":["trace[1396259985] 'process raft request' (duration: 105.047537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:03.762068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.441855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:54:03.762139Z","caller":"traceutil/trace.go:171","msg":"trace[328491577] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1919; }","duration":"105.575968ms","start":"2026-04-20T05:54:03.656543Z","end":"2026-04-20T05:54:03.762119Z","steps":["trace[328491577] 'agreement among raft nodes before linearized reading' (duration: 105.438905ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:54:12.044643Z","caller":"traceutil/trace.go:171","msg":"trace[575208823] transaction","detail":"{read_only:false; response_revision:1984; number_of_response:1; }","duration":"107.457036ms","start":"2026-04-20T05:54:11.937151Z","end":"2026-04-20T05:54:12.044608Z","steps":["trace[575208823] 'process raft request' (duration: 66.244605ms)","trace[575208823] 'compare' (duration: 40.899325ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:54:33.175731Z","caller":"traceutil/trace.go:171","msg":"trace[549452861] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"111.525494ms","start":"2026-04-20T05:54:33.064171Z","end":"2026-04-20T05:54:33.175696Z","steps":["trace[549452861] 'process raft request' (duration: 111.34159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:54:39.64742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.821718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T05:54:39.647533Z","caller":"traceutil/trace.go:171","msg":"trace[1872915735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2128; }","duration":"266.986082ms","start":"2026-04-20T05:54:39.380522Z","end":"2026-04-20T05:54:39.647509Z","steps":["trace[1872915735] 'range keys from in-memory index tree' (duration: 266.606205ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:55:28.011057Z","caller":"traceutil/trace.go:171","msg":"trace[55611] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"110.278856ms","start":"2026-04-20T05:55:27.900752Z","end":"2026-04-20T05:55:28.01103Z","steps":["trace[55611] 'process raft request' (duration: 110.128333ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:55:28.59981Z","caller":"traceutil/trace.go:171","msg":"trace[311350302] linearizableReadLoop","detail":"{readStateIndex:2666; appliedIndex:2665; }","duration":"179.439895ms","start":"2026-04-20T05:55:28.420341Z","end":"2026-04-20T05:55:28.59978Z","steps":["trace[311350302] 'read index received' (duration: 179.364914ms)","trace[311350302] 'applied index is now lower than readState.Index' (duration: 73.611µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:55:28.599915Z","caller":"traceutil/trace.go:171","msg":"trace[1409199422] transaction","detail":"{read_only:false; response_revision:2568; number_of_response:1; }","duration":"252.233452ms","start":"2026-04-20T05:55:28.347654Z","end":"2026-04-20T05:55:28.599887Z","steps":["trace[1409199422] 'process raft request' (duration: 251.915826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:55:28.599986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.639648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:55:28.600035Z","caller":"traceutil/trace.go:171","msg":"trace[1074150927] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2568; }","duration":"179.753151ms","start":"2026-04-20T05:55:28.420265Z","end":"2026-04-20T05:55:28.600018Z","steps":["trace[1074150927] 'agreement among raft nodes before linearized reading' (duration: 179.654379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:56:02.901826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.27894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:56:02.902161Z","caller":"traceutil/trace.go:171","msg":"trace[238279709] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"473.610811ms","start":"2026-04-20T05:56:02.428512Z","end":"2026-04-20T05:56:02.902123Z","steps":["trace[238279709] 'process raft request' (duration: 171.916423ms)","trace[238279709] 'compare' (duration: 301.112707ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:56:02.902176Z","caller":"traceutil/trace.go:171","msg":"trace[1029619506] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"162.704099ms","start":"2026-04-20T05:56:02.739453Z","end":"2026-04-20T05:56:02.902157Z","steps":["trace[1029619506] 'process raft request' (duration: 162.646418ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:56:02.902207Z","caller":"traceutil/trace.go:171","msg":"trace[527594776] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"162.836571ms","start":"2026-04-20T05:56:02.739332Z","end":"2026-04-20T05:56:02.902169Z","steps":["trace[527594776] 'process raft request' (duration: 162.717129ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:56:02.902158Z","caller":"traceutil/trace.go:171","msg":"trace[1880156632] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"278.406257ms","start":"2026-04-20T05:56:02.623713Z","end":"2026-04-20T05:56:02.902119Z","steps":["trace[1880156632] 'process raft request' (duration: 278.252534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:56:02.902277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:56:02.428496Z","time spent":"473.731853ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T05:56:02.902438Z","caller":"traceutil/trace.go:171","msg":"trace[1126467594] linearizableReadLoop","detail":"{readStateIndex:2879; appliedIndex:2877; }","duration":"216.022358ms","start":"2026-04-20T05:56:02.686139Z","end":"2026-04-20T05:56:02.902162Z","steps":["trace[1126467594] 'read index received' (duration: 214.06294ms)","trace[1126467594] 'applied index is now lower than readState.Index' (duration: 1.937777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:56:02.902661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.505146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-20T05:56:02.902766Z","caller":"traceutil/trace.go:171","msg":"trace[433203308] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2775; }","duration":"216.621748ms","start":"2026-04-20T05:56:02.686121Z","end":"2026-04-20T05:56:02.902742Z","steps":["trace[433203308] 'agreement among raft nodes before linearized reading' (duration: 216.15428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:56:02.903064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.110896ms","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-04-20T05:56:02.903138Z","caller":"traceutil/trace.go:171","msg":"trace[764984631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2775; }","duration":"135.213187ms","start":"2026-04-20T05:56:02.767906Z","end":"2026-04-20T05:56:02.903119Z","steps":["trace[764984631] 'agreement among raft nodes before linearized reading' (duration: 135.029494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:33.749687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.133187ms","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":"info","ts":"2026-04-20T05:58:33.749798Z","caller":"traceutil/trace.go:171","msg":"trace[1053821504] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:3768; }","duration":"138.308871ms","start":"2026-04-20T05:58:33.611464Z","end":"2026-04-20T05:58:33.749773Z","steps":["trace[1053821504] 'count revisions from in-memory index tree' (duration: 138.017765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:33.749992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.420292ms","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-04-20T05:58:33.75008Z","caller":"traceutil/trace.go:171","msg":"trace[639033194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3768; }","duration":"125.389911ms","start":"2026-04-20T05:58:33.62467Z","end":"2026-04-20T05:58:33.75006Z","steps":["trace[639033194] 'range keys from in-memory index tree' (duration: 124.242239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:58:33.750077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.002311ms","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-04-20T05:58:33.750146Z","caller":"traceutil/trace.go:171","msg":"trace[479081753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3768; }","duration":"112.142254ms","start":"2026-04-20T05:58:33.637989Z","end":"2026-04-20T05:58:33.750131Z","steps":["trace[479081753] 'range keys from in-memory index tree' (duration: 111.802737ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:59:55.567019Z","caller":"traceutil/trace.go:171","msg":"trace[1027589952] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"121.16021ms","start":"2026-04-20T05:59:55.445833Z","end":"2026-04-20T05:59:55.566993Z","steps":["trace[1027589952] 'process raft request' (duration: 120.747802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:00.113405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.284876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T06:00:00.113841Z","caller":"traceutil/trace.go:171","msg":"trace[894766438] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"416.123013ms","start":"2026-04-20T05:59:59.697675Z","end":"2026-04-20T06:00:00.113798Z","steps":["trace[894766438] 'process raft request' (duration: 170.273567ms)","trace[894766438] 'compare' (duration: 245.022241ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:00.113959Z","caller":"traceutil/trace.go:171","msg":"trace[1208028077] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"274.710745ms","start":"2026-04-20T05:59:59.839228Z","end":"2026-04-20T06:00:00.113938Z","steps":["trace[1208028077] 'process raft request' (duration: 274.665594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:00.114029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:59:59.697654Z","time spent":"416.263026ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:00:00.113972Z","caller":"traceutil/trace.go:171","msg":"trace[543287455] linearizableReadLoop","detail":"{readStateIndex:5134; appliedIndex:5133; }","duration":"353.501565ms","start":"2026-04-20T05:59:59.760372Z","end":"2026-04-20T06:00:00.113874Z","steps":["trace[543287455] 'read index received' (duration: 107.545706ms)","trace[543287455] 'applied index is now lower than readState.Index' (duration: 245.950709ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:00.114224Z","caller":"traceutil/trace.go:171","msg":"trace[602679364] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"286.291051ms","start":"2026-04-20T05:59:59.827924Z","end":"2026-04-20T06:00:00.114216Z","steps":["trace[602679364] 'process raft request' (duration: 285.866893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:00.114197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.812601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T06:00:00.11428Z","caller":"traceutil/trace.go:171","msg":"trace[1438453820] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4951; }","duration":"353.963084ms","start":"2026-04-20T05:59:59.760297Z","end":"2026-04-20T06:00:00.11426Z","steps":["trace[1438453820] 'agreement among raft nodes before linearized reading' (duration: 353.653568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:00.114326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:59:59.760278Z","time spent":"354.039295ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T06:00:00.150231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.71144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:00:00.150312Z","caller":"traceutil/trace.go:171","msg":"trace[2002999448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4952; }","duration":"197.849961ms","start":"2026-04-20T05:59:59.952445Z","end":"2026-04-20T06:00:00.150295Z","steps":["trace[2002999448] 'agreement among raft nodes before linearized reading' (duration: 197.701388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:00.150534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.990603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9ncsw\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T06:00:00.15063Z","caller":"traceutil/trace.go:171","msg":"trace[1447794400] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9ncsw; range_end:; response_count:1; response_revision:4952; }","duration":"363.128495ms","start":"2026-04-20T05:59:59.787476Z","end":"2026-04-20T06:00:00.150605Z","steps":["trace[1447794400] 'agreement among raft nodes before linearized reading' (duration: 362.83827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:00.150534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.396631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T06:00:00.150695Z","caller":"traceutil/trace.go:171","msg":"trace[1985580461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4952; }","duration":"369.613585ms","start":"2026-04-20T05:59:59.781066Z","end":"2026-04-20T06:00:00.150679Z","steps":["trace[1985580461] 'agreement among raft nodes before linearized reading' (duration: 369.286359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:00.150692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:59:59.787467Z","time spent":"363.211546ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-9ncsw\" "} {"level":"warn","ts":"2026-04-20T06:00:00.150739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:59:59.781044Z","time spent":"369.685416ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T06:00:02.355393Z","caller":"traceutil/trace.go:171","msg":"trace[625857456] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"234.872561ms","start":"2026-04-20T06:00:02.120499Z","end":"2026-04-20T06:00:02.355371Z","steps":["trace[625857456] 'process raft request' (duration: 234.720238ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:02.379147Z","caller":"traceutil/trace.go:171","msg":"trace[1486770821] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5178; }","duration":"218.600147ms","start":"2026-04-20T06:00:02.160507Z","end":"2026-04-20T06:00:02.379107Z","steps":["trace[1486770821] 'read index received' (duration: 194.742632ms)","trace[1486770821] 'applied index is now lower than readState.Index' (duration: 23.855765ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:02.379253Z","caller":"traceutil/trace.go:171","msg":"trace[480807632] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"258.594344ms","start":"2026-04-20T06:00:02.120617Z","end":"2026-04-20T06:00:02.379211Z","steps":["trace[480807632] 'process raft request' (duration: 258.259177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:02.379446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.923105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9ncsw\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T06:00:02.379537Z","caller":"traceutil/trace.go:171","msg":"trace[1217832552] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9ncsw; range_end:; response_count:1; response_revision:4993; }","duration":"219.075758ms","start":"2026-04-20T06:00:02.16044Z","end":"2026-04-20T06:00:02.379516Z","steps":["trace[1217832552] 'agreement among raft nodes before linearized reading' (duration: 218.805163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:02.379674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.997939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T06:00:02.379776Z","caller":"traceutil/trace.go:171","msg":"trace[1854448094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4993; }","duration":"216.165132ms","start":"2026-04-20T06:00:02.163592Z","end":"2026-04-20T06:00:02.379757Z","steps":["trace[1854448094] 'agreement among raft nodes before linearized reading' (duration: 215.761225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:02.380043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.994415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777477"} {"level":"info","ts":"2026-04-20T06:00:02.380098Z","caller":"traceutil/trace.go:171","msg":"trace[192363060] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4993; }","duration":"152.086067ms","start":"2026-04-20T06:00:02.227997Z","end":"2026-04-20T06:00:02.380083Z","steps":["trace[192363060] 'agreement among raft nodes before linearized reading' (duration: 151.641759ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:36.655579Z","caller":"traceutil/trace.go:171","msg":"trace[2083694533] transaction","detail":"{read_only:false; response_revision:5527; number_of_response:1; }","duration":"169.040903ms","start":"2026-04-20T06:00:36.486513Z","end":"2026-04-20T06:00:36.655554Z","steps":["trace[2083694533] 'process raft request' (duration: 104.788334ms)","trace[2083694533] 'compare' (duration: 64.085176ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:36.655798Z","caller":"traceutil/trace.go:171","msg":"trace[1966392954] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"166.818671ms","start":"2026-04-20T06:00:36.488951Z","end":"2026-04-20T06:00:36.65577Z","steps":["trace[1966392954] 'process raft request' (duration: 166.567577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.165784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878529844695885,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T06:00:37.586472Z","caller":"traceutil/trace.go:171","msg":"trace[1700647065] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"926.734708ms","start":"2026-04-20T06:00:36.659689Z","end":"2026-04-20T06:00:37.586423Z","steps":["trace[1700647065] 'process raft request' (duration: 926.541465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.586639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:36.659673Z","time spent":"926.877511ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:00:37.602584Z","caller":"traceutil/trace.go:171","msg":"trace[662953874] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"717.501656ms","start":"2026-04-20T06:00:36.885063Z","end":"2026-04-20T06:00:37.602565Z","steps":["trace[662953874] 'process raft request' (duration: 717.366394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.6027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:36.885043Z","time spent":"717.600027ms","remote":"127.0.0.1:59714","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-04-20T06:00:37.602727Z","caller":"traceutil/trace.go:171","msg":"trace[1594594872] linearizableReadLoop","detail":"{readStateIndex:5731; appliedIndex:5729; }","duration":"937.637302ms","start":"2026-04-20T06:00:36.665048Z","end":"2026-04-20T06:00:37.602685Z","steps":["trace[1594594872] 'read index received' (duration: 921.159674ms)","trace[1594594872] 'applied index is now lower than readState.Index' (duration: 16.475508ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:37.60275Z","caller":"traceutil/trace.go:171","msg":"trace[215164106] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"187.989187ms","start":"2026-04-20T06:00:37.414734Z","end":"2026-04-20T06:00:37.602723Z","steps":["trace[215164106] 'process raft request' (duration: 187.788213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.603001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.572243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:00:37.60304Z","caller":"traceutil/trace.go:171","msg":"trace[27755002] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5531; }","duration":"526.663754ms","start":"2026-04-20T06:00:37.076365Z","end":"2026-04-20T06:00:37.603029Z","steps":["trace[27755002] 'agreement among raft nodes before linearized reading' (duration: 526.556332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.603061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.532087ms","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":"warn","ts":"2026-04-20T06:00:37.603078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.076353Z","time spent":"526.716046ms","remote":"127.0.0.1:59850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-04-20T06:00:37.603054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.981418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T06:00:37.603078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.877142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T06:00:37.603102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.299133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9ncsw\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-04-20T06:00:37.603118Z","caller":"traceutil/trace.go:171","msg":"trace[1536141347] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5531; }","duration":"902.64018ms","start":"2026-04-20T06:00:36.700462Z","end":"2026-04-20T06:00:37.603102Z","steps":["trace[1536141347] 'agreement among raft nodes before linearized reading' (duration: 902.455366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.60312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.160425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-04-20T06:00:37.603136Z","caller":"traceutil/trace.go:171","msg":"trace[1548947052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5531; }","duration":"938.095611ms","start":"2026-04-20T06:00:36.665019Z","end":"2026-04-20T06:00:37.603115Z","steps":["trace[1548947052] 'agreement among raft nodes before linearized reading' (duration: 937.853947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.603134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.263146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-20T06:00:37.603155Z","caller":"traceutil/trace.go:171","msg":"trace[832179968] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9ncsw; range_end:; response_count:1; response_revision:5531; }","duration":"827.395245ms","start":"2026-04-20T06:00:36.775747Z","end":"2026-04-20T06:00:37.603142Z","steps":["trace[832179968] 'agreement among raft nodes before linearized reading' (duration: 827.253413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.60316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:36.700445Z","time spent":"902.70682ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T06:00:37.603161Z","caller":"traceutil/trace.go:171","msg":"trace[1350993754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5531; }","duration":"651.987723ms","start":"2026-04-20T06:00:36.951148Z","end":"2026-04-20T06:00:37.603136Z","steps":["trace[1350993754] 'agreement among raft nodes before linearized reading' (duration: 651.848841ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:37.603174Z","caller":"traceutil/trace.go:171","msg":"trace[1753958015] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5531; }","duration":"549.242486ms","start":"2026-04-20T06:00:37.053916Z","end":"2026-04-20T06:00:37.603159Z","steps":["trace[1753958015] 'agreement among raft nodes before linearized reading' (duration: 549.059792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.603183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:36.665004Z","time spent":"938.171122ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T06:00:37.603192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:36.775726Z","time spent":"827.459506ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-9ncsw\" "} {"level":"info","ts":"2026-04-20T06:00:37.603197Z","caller":"traceutil/trace.go:171","msg":"trace[2092201710] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5531; }","duration":"453.390608ms","start":"2026-04-20T06:00:37.149789Z","end":"2026-04-20T06:00:37.60318Z","steps":["trace[2092201710] 'agreement among raft nodes before linearized reading' (duration: 453.150404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.603212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:36.951136Z","time spent":"652.065444ms","remote":"127.0.0.1:59418","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-04-20T06:00:37.603214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.053897Z","time spent":"549.307397ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-20T06:00:37.603237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.149757Z","time spent":"453.47179ms","remote":"127.0.0.1:59590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-20T06:00:37.603254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.619537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T06:00:37.603269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.52156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T06:00:37.603289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.545678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T06:00:37.60331Z","caller":"traceutil/trace.go:171","msg":"trace[88288219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5531; }","duration":"836.697008ms","start":"2026-04-20T06:00:36.766597Z","end":"2026-04-20T06:00:37.603294Z","steps":["trace[88288219] 'agreement among raft nodes before linearized reading' (duration: 836.521895ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:37.603323Z","caller":"traceutil/trace.go:171","msg":"trace[1074829643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5531; }","duration":"475.615643ms","start":"2026-04-20T06:00:37.127692Z","end":"2026-04-20T06:00:37.603308Z","steps":["trace[1074829643] 'agreement among raft nodes before linearized reading' (duration: 475.470651ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:37.60333Z","caller":"traceutil/trace.go:171","msg":"trace[793858457] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5531; }","duration":"269.652469ms","start":"2026-04-20T06:00:37.333666Z","end":"2026-04-20T06:00:37.603319Z","steps":["trace[793858457] 'agreement among raft nodes before linearized reading' (duration: 269.539927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.603347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:36.766588Z","time spent":"836.750449ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T06:00:37.603359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.127673Z","time spent":"475.678134ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T06:00:37.894722Z","caller":"traceutil/trace.go:171","msg":"trace[1846860740] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"286.075697ms","start":"2026-04-20T06:00:37.608618Z","end":"2026-04-20T06:00:37.894694Z","steps":["trace[1846860740] 'process raft request' (duration: 285.989325ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:37.894789Z","caller":"traceutil/trace.go:171","msg":"trace[431956552] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"286.193759ms","start":"2026-04-20T06:00:37.608558Z","end":"2026-04-20T06:00:37.894752Z","steps":["trace[431956552] 'process raft request' (duration: 285.876593ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:37.895066Z","caller":"traceutil/trace.go:171","msg":"trace[2107437743] linearizableReadLoop","detail":"{readStateIndex:5735; appliedIndex:5732; }","duration":"286.337482ms","start":"2026-04-20T06:00:37.608699Z","end":"2026-04-20T06:00:37.895036Z","steps":["trace[2107437743] 'read index received' (duration: 21.435199ms)","trace[2107437743] 'applied index is now lower than readState.Index' (duration: 264.900743ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:37.895119Z","caller":"traceutil/trace.go:171","msg":"trace[233570137] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"286.405893ms","start":"2026-04-20T06:00:37.608699Z","end":"2026-04-20T06:00:37.895105Z","steps":["trace[233570137] 'process raft request' (duration: 285.953514ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:37.895109Z","caller":"traceutil/trace.go:171","msg":"trace[955142619] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"279.849051ms","start":"2026-04-20T06:00:37.615246Z","end":"2026-04-20T06:00:37.895095Z","steps":["trace[955142619] 'process raft request' (duration: 279.646146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.895405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.855669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-20T06:00:37.895473Z","caller":"traceutil/trace.go:171","msg":"trace[1297521552] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5536; }","duration":"273.94155ms","start":"2026-04-20T06:00:37.621499Z","end":"2026-04-20T06:00:37.89544Z","steps":["trace[1297521552] 'agreement among raft nodes before linearized reading' (duration: 273.744197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:37.89563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.789213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-20T06:00:37.895869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.122696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81612"} {"level":"info","ts":"2026-04-20T06:00:37.897061Z","caller":"traceutil/trace.go:171","msg":"trace[908740926] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5536; }","duration":"288.347759ms","start":"2026-04-20T06:00:37.60867Z","end":"2026-04-20T06:00:37.897018Z","steps":["trace[908740926] 'agreement among raft nodes before linearized reading' (duration: 286.475575ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:37.89686Z","caller":"traceutil/trace.go:171","msg":"trace[1291957172] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5536; }","duration":"271.022576ms","start":"2026-04-20T06:00:37.625785Z","end":"2026-04-20T06:00:37.896807Z","steps":["trace[1291957172] 'agreement among raft nodes before linearized reading' (duration: 269.461457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:38.425998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.829154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T06:00:38.426166Z","caller":"traceutil/trace.go:171","msg":"trace[2072497198] transaction","detail":"{read_only:false; response_revision:5538; number_of_response:1; }","duration":"523.218531ms","start":"2026-04-20T06:00:37.902917Z","end":"2026-04-20T06:00:38.426135Z","steps":["trace[2072497198] 'process raft request' (duration: 320.114862ms)","trace[2072497198] 'compare' (duration: 202.649261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:00:38.426233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.9029Z","time spent":"523.306032ms","remote":"127.0.0.1:59604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:00:38.426673Z","caller":"traceutil/trace.go:171","msg":"trace[136591772] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"523.361464ms","start":"2026-04-20T06:00:37.903257Z","end":"2026-04-20T06:00:38.426618Z","steps":["trace[136591772] 'process raft request' (duration: 522.850994ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:38.42671Z","caller":"traceutil/trace.go:171","msg":"trace[1617729549] linearizableReadLoop","detail":"{readStateIndex:5739; appliedIndex:5737; }","duration":"523.529926ms","start":"2026-04-20T06:00:37.903153Z","end":"2026-04-20T06:00:38.426683Z","steps":["trace[1617729549] 'read index received' (duration: 69.987495ms)","trace[1617729549] 'applied index is now lower than readState.Index' (duration: 453.541411ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:00:38.426795Z","caller":"traceutil/trace.go:171","msg":"trace[1220194884] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"523.397873ms","start":"2026-04-20T06:00:37.903388Z","end":"2026-04-20T06:00:38.426786Z","steps":["trace[1220194884] 'process raft request' (duration: 523.156458ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:38.42688Z","caller":"traceutil/trace.go:171","msg":"trace[1994311063] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"521.242953ms","start":"2026-04-20T06:00:37.905624Z","end":"2026-04-20T06:00:38.426866Z","steps":["trace[1994311063] 'process raft request' (duration: 520.981368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:38.426997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.905607Z","time spent":"521.307915ms","remote":"127.0.0.1:59604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":951,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:00:38.427029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.621665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-20T06:00:38.427023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.249848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:00:38.427139Z","caller":"traceutil/trace.go:171","msg":"trace[1880760341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5541; }","duration":"473.377731ms","start":"2026-04-20T06:00:37.953734Z","end":"2026-04-20T06:00:38.427111Z","steps":["trace[1880760341] 'agreement among raft nodes before linearized reading' (duration: 473.195397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:38.427162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.006656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10685"} {"level":"warn","ts":"2026-04-20T06:00:38.426885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.903383Z","time spent":"523.470004ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:00:38.427195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.95369Z","time spent":"473.494274ms","remote":"127.0.0.1:59418","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-04-20T06:00:38.427257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.295261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:00:38.427293Z","caller":"traceutil/trace.go:171","msg":"trace[244846764] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:5541; }","duration":"100.376343ms","start":"2026-04-20T06:00:38.326908Z","end":"2026-04-20T06:00:38.427284Z","steps":["trace[244846764] 'agreement among raft nodes before linearized reading' (duration: 100.318542ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:38.427078Z","caller":"traceutil/trace.go:171","msg":"trace[1013032379] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5541; }","duration":"310.706627ms","start":"2026-04-20T06:00:38.116355Z","end":"2026-04-20T06:00:38.427062Z","steps":["trace[1013032379] 'agreement among raft nodes before linearized reading' (duration: 310.576143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:38.42747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.388813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T06:00:38.427498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:38.116333Z","time spent":"311.151854ms","remote":"127.0.0.1:59590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-20T06:00:38.427516Z","caller":"traceutil/trace.go:171","msg":"trace[1380420372] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5541; }","duration":"450.473804ms","start":"2026-04-20T06:00:37.977031Z","end":"2026-04-20T06:00:38.427504Z","steps":["trace[1380420372] 'agreement among raft nodes before linearized reading' (duration: 450.392872ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:00:38.427194Z","caller":"traceutil/trace.go:171","msg":"trace[788436927] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5541; }","duration":"524.061057ms","start":"2026-04-20T06:00:37.903125Z","end":"2026-04-20T06:00:38.427186Z","steps":["trace[788436927] 'agreement among raft nodes before linearized reading' (duration: 523.971865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:38.427553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.977009Z","time spent":"450.532885ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T06:00:38.427579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.903115Z","time spent":"524.450394ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10708,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-20T06:00:38.427679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.87708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:00:38.427725Z","caller":"traceutil/trace.go:171","msg":"trace[1534544387] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:5541; }","duration":"473.953402ms","start":"2026-04-20T06:00:37.953759Z","end":"2026-04-20T06:00:38.427712Z","steps":["trace[1534544387] 'agreement among raft nodes before linearized reading' (duration: 473.883011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:00:38.427759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.953737Z","time spent":"474.015073ms","remote":"127.0.0.1:40648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T06:00:38.428301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:00:37.903251Z","time spent":"524.152159ms","remote":"127.0.0.1:59730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1506,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:00:38.591701Z","caller":"traceutil/trace.go:171","msg":"trace[475080712] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"145.752009ms","start":"2026-04-20T06:00:38.445912Z","end":"2026-04-20T06:00:38.591664Z","steps":["trace[475080712] 'process raft request' (duration: 102.540793ms)","trace[475080712] 'compare' (duration: 42.999832ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:01:42.410386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2988} {"level":"info","ts":"2026-04-20T06:01:42.482495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2988,"took":"70.820171ms","hash":4162428626,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":33894400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T06:01:42.482553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4162428626,"revision":2988,"compact-revision":-1} {"level":"warn","ts":"2026-04-20T06:06:23.591321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.529417ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:06:23.591455Z","caller":"traceutil/trace.go:171","msg":"trace[1733456771] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8184; }","duration":"527.678649ms","start":"2026-04-20T06:06:23.063759Z","end":"2026-04-20T06:06:23.591437Z","steps":["trace[1733456771] 'range keys from in-memory index tree' (duration: 527.513147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:23.5918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.42117ms","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-04-20T06:06:23.591897Z","caller":"traceutil/trace.go:171","msg":"trace[650006424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8184; }","duration":"117.570513ms","start":"2026-04-20T06:06:23.474308Z","end":"2026-04-20T06:06:23.591879Z","steps":["trace[650006424] 'range keys from in-memory index tree' (duration: 117.216767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:23.591968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.589728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T06:06:23.592049Z","caller":"traceutil/trace.go:171","msg":"trace[585221470] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8184; }","duration":"257.71975ms","start":"2026-04-20T06:06:23.334315Z","end":"2026-04-20T06:06:23.592034Z","steps":["trace[585221470] 'count revisions from in-memory index tree' (duration: 257.493754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:23.592061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.891905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T06:06:23.592142Z","caller":"traceutil/trace.go:171","msg":"trace[1188455243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8184; }","duration":"112.998478ms","start":"2026-04-20T06:06:23.479127Z","end":"2026-04-20T06:06:23.592125Z","steps":["trace[1188455243] 'range keys from in-memory index tree' (duration: 112.444487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:35.837559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.996135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T06:06:35.837568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.845559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T06:06:35.837661Z","caller":"traceutil/trace.go:171","msg":"trace[1291685515] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8249; }","duration":"107.176209ms","start":"2026-04-20T06:06:35.730465Z","end":"2026-04-20T06:06:35.837642Z","steps":["trace[1291685515] 'range keys from in-memory index tree' (duration: 106.73184ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:35.837685Z","caller":"traceutil/trace.go:171","msg":"trace[1402458711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8249; }","duration":"165.002072ms","start":"2026-04-20T06:06:35.672659Z","end":"2026-04-20T06:06:35.837661Z","steps":["trace[1402458711] 'range keys from in-memory index tree' (duration: 164.675936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:35.837563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.104575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-20T06:06:35.837757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.148007ms","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-04-20T06:06:35.837841Z","caller":"traceutil/trace.go:171","msg":"trace[107008426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8249; }","duration":"116.298559ms","start":"2026-04-20T06:06:35.721522Z","end":"2026-04-20T06:06:35.837821Z","steps":["trace[107008426] 'range keys from in-memory index tree' (duration: 115.960543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:35.837831Z","caller":"traceutil/trace.go:171","msg":"trace[1542539198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8249; }","duration":"164.409602ms","start":"2026-04-20T06:06:35.673386Z","end":"2026-04-20T06:06:35.837795Z","steps":["trace[1542539198] 'range keys from in-memory index tree' (duration: 163.925772ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:36.130909Z","caller":"traceutil/trace.go:171","msg":"trace[753378812] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"353.74631ms","start":"2026-04-20T06:06:35.777137Z","end":"2026-04-20T06:06:36.130883Z","steps":["trace[753378812] 'process raft request' (duration: 353.532167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.131083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:35.777104Z","time spent":"353.883722ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:36.455376Z","caller":"traceutil/trace.go:171","msg":"trace[207932071] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"612.678283ms","start":"2026-04-20T06:06:35.84267Z","end":"2026-04-20T06:06:36.455349Z","steps":["trace[207932071] 'process raft request' (duration: 612.460139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.455557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:35.842647Z","time spent":"612.811575ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:36.455932Z","caller":"traceutil/trace.go:171","msg":"trace[1997152780] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"613.031229ms","start":"2026-04-20T06:06:35.842751Z","end":"2026-04-20T06:06:36.455782Z","steps":["trace[1997152780] 'process raft request' (duration: 612.54725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.456238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:35.842716Z","time spent":"613.368606ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:36.456217Z","caller":"traceutil/trace.go:171","msg":"trace[592588371] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"613.030849ms","start":"2026-04-20T06:06:35.843153Z","end":"2026-04-20T06:06:36.456184Z","steps":["trace[592588371] 'process raft request' (duration: 612.48703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.456435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:35.843141Z","time spent":"613.178572ms","remote":"127.0.0.1:59714","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-04-20T06:06:36.456493Z","caller":"traceutil/trace.go:171","msg":"trace[109511404] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"613.288935ms","start":"2026-04-20T06:06:35.843182Z","end":"2026-04-20T06:06:36.456471Z","steps":["trace[109511404] 'process raft request' (duration: 612.889976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.456564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:35.843153Z","time spent":"613.366606ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:36.456567Z","caller":"traceutil/trace.go:171","msg":"trace[9107258] linearizableReadLoop","detail":"{readStateIndex:8543; appliedIndex:8538; }","duration":"585.81757ms","start":"2026-04-20T06:06:35.870728Z","end":"2026-04-20T06:06:36.456546Z","steps":["trace[9107258] 'read index received' (duration: 260.005004ms)","trace[9107258] 'applied index is now lower than readState.Index' (duration: 325.810566ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:36.456777Z","caller":"traceutil/trace.go:171","msg":"trace[1064405908] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"380.514002ms","start":"2026-04-20T06:06:36.076242Z","end":"2026-04-20T06:06:36.456756Z","steps":["trace[1064405908] 'process raft request' (duration: 380.219695ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:36.456777Z","caller":"traceutil/trace.go:171","msg":"trace[924813888] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"381.45422ms","start":"2026-04-20T06:06:36.07529Z","end":"2026-04-20T06:06:36.456744Z","steps":["trace[924813888] 'process raft request' (duration: 381.066092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.456889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.594262ms","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-04-20T06:06:36.456968Z","caller":"traceutil/trace.go:171","msg":"trace[23611468] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8256; }","duration":"484.730664ms","start":"2026-04-20T06:06:35.97222Z","end":"2026-04-20T06:06:36.45695Z","steps":["trace[23611468] 'agreement among raft nodes before linearized reading' (duration: 484.571811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.45702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:35.972177Z","time spent":"484.828316ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T06:06:36.457014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.281888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9ncsw\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-04-20T06:06:36.457068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:36.075238Z","time spent":"381.653333ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:06:36.456902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:36.076228Z","time spent":"380.619403ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:06:36.457182Z","caller":"traceutil/trace.go:171","msg":"trace[1704894462] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9ncsw; range_end:; response_count:1; response_revision:8256; }","duration":"586.43589ms","start":"2026-04-20T06:06:35.870686Z","end":"2026-04-20T06:06:36.457122Z","steps":["trace[1704894462] 'agreement among raft nodes before linearized reading' (duration: 585.920161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.457329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:06:35.870668Z","time spent":"586.597754ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-9ncsw\" "} {"level":"warn","ts":"2026-04-20T06:06:36.457922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.590474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T06:06:36.457996Z","caller":"traceutil/trace.go:171","msg":"trace[1597407769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8256; }","duration":"212.715937ms","start":"2026-04-20T06:06:36.245264Z","end":"2026-04-20T06:06:36.45798Z","steps":["trace[1597407769] 'agreement among raft nodes before linearized reading' (duration: 211.84525ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:36.656246Z","caller":"traceutil/trace.go:171","msg":"trace[52816584] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"192.942916ms","start":"2026-04-20T06:06:36.463259Z","end":"2026-04-20T06:06:36.656202Z","steps":["trace[52816584] 'process raft request' (duration: 141.845429ms)","trace[52816584] 'compare' (duration: 50.843293ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:36.656456Z","caller":"traceutil/trace.go:171","msg":"trace[1555499853] linearizableReadLoop","detail":"{readStateIndex:8547; appliedIndex:8545; }","duration":"157.066743ms","start":"2026-04-20T06:06:36.49936Z","end":"2026-04-20T06:06:36.656427Z","steps":["trace[1555499853] 'read index received' (duration: 105.772663ms)","trace[1555499853] 'applied index is now lower than readState.Index' (duration: 51.29243ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:06:36.656567Z","caller":"traceutil/trace.go:171","msg":"trace[960537099] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"193.087838ms","start":"2026-04-20T06:06:36.463345Z","end":"2026-04-20T06:06:36.656433Z","steps":["trace[960537099] 'process raft request' (duration: 192.790993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:06:36.656714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.335558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6f8466d79d-4lx52.18a7fb9099e5bf98\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T06:06:36.656767Z","caller":"traceutil/trace.go:171","msg":"trace[965820787] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6f8466d79d-4lx52.18a7fb9099e5bf98; range_end:; response_count:1; response_revision:8258; }","duration":"157.452611ms","start":"2026-04-20T06:06:36.499296Z","end":"2026-04-20T06:06:36.656748Z","steps":["trace[965820787] 'agreement among raft nodes before linearized reading' (duration: 157.244866ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:06:42.417405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6092} {"level":"info","ts":"2026-04-20T06:06:42.52316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6092,"took":"103.710483ms","hash":2991036641,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":23744512,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-20T06:06:42.523221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2991036641,"revision":6092,"compact-revision":2988} {"level":"info","ts":"2026-04-20T06:07:05.15022Z","caller":"traceutil/trace.go:171","msg":"trace[542329186] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"361.512125ms","start":"2026-04-20T06:07:04.788682Z","end":"2026-04-20T06:07:05.150194Z","steps":["trace[542329186] 'process raft request' (duration: 361.380423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:05.150583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:04.788655Z","time spent":"361.842621ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:07:05.151811Z","caller":"traceutil/trace.go:171","msg":"trace[2134390522] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"358.783934ms","start":"2026-04-20T06:07:04.79301Z","end":"2026-04-20T06:07:05.151794Z","steps":["trace[2134390522] 'process raft request' (duration: 358.361736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:05.152033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:04.792991Z","time spent":"358.994828ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:07:05.151769Z","caller":"traceutil/trace.go:171","msg":"trace[650224739] linearizableReadLoop","detail":"{readStateIndex:8759; appliedIndex:8758; }","duration":"199.134212ms","start":"2026-04-20T06:07:04.952569Z","end":"2026-04-20T06:07:05.151703Z","steps":["trace[650224739] 'read index received' (duration: 197.630584ms)","trace[650224739] 'applied index is now lower than readState.Index' (duration: 1.502078ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:07:05.151975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.350406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:07:05.152387Z","caller":"traceutil/trace.go:171","msg":"trace[2115825844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8462; }","duration":"199.864866ms","start":"2026-04-20T06:07:04.952493Z","end":"2026-04-20T06:07:05.152358Z","steps":["trace[2115825844] 'agreement among raft nodes before linearized reading' (duration: 199.374486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:05.273969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.906407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:07:05.274066Z","caller":"traceutil/trace.go:171","msg":"trace[1798806560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8462; }","duration":"120.080421ms","start":"2026-04-20T06:07:05.153962Z","end":"2026-04-20T06:07:05.274042Z","steps":["trace[1798806560] 'range keys from in-memory index tree' (duration: 119.782295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:05.274127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.798789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19383"} {"level":"info","ts":"2026-04-20T06:07:05.274213Z","caller":"traceutil/trace.go:171","msg":"trace[1793240561] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8462; }","duration":"100.943242ms","start":"2026-04-20T06:07:05.173252Z","end":"2026-04-20T06:07:05.274195Z","steps":["trace[1793240561] 'range keys from in-memory index tree' (duration: 100.600265ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:05.407691Z","caller":"traceutil/trace.go:171","msg":"trace[1934686277] linearizableReadLoop","detail":"{readStateIndex:8760; appliedIndex:8759; }","duration":"127.865256ms","start":"2026-04-20T06:07:05.279803Z","end":"2026-04-20T06:07:05.407668Z","steps":["trace[1934686277] 'read index received' (duration: 127.689383ms)","trace[1934686277] 'applied index is now lower than readState.Index' (duration: 174.973µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:07:05.407903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.09098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19383"} {"level":"info","ts":"2026-04-20T06:07:05.407934Z","caller":"traceutil/trace.go:171","msg":"trace[920335437] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8463; }","duration":"128.276474ms","start":"2026-04-20T06:07:05.279648Z","end":"2026-04-20T06:07:05.407924Z","steps":["trace[920335437] 'agreement among raft nodes before linearized reading' (duration: 128.109551ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:05.408285Z","caller":"traceutil/trace.go:171","msg":"trace[1893703779] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"231.741793ms","start":"2026-04-20T06:07:05.176531Z","end":"2026-04-20T06:07:05.408272Z","steps":["trace[1893703779] 'process raft request' (duration: 231.01895ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:17.223528Z","caller":"traceutil/trace.go:171","msg":"trace[1335407659] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"324.851389ms","start":"2026-04-20T06:07:16.898656Z","end":"2026-04-20T06:07:17.223508Z","steps":["trace[1335407659] 'process raft request' (duration: 245.347728ms)","trace[1335407659] 'compare' (duration: 79.251126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:07:17.223643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:16.898648Z","time spent":"324.9374ms","remote":"127.0.0.1:59624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4633,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T06:07:18.451652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256878529844704223,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T06:07:18.621577Z","caller":"traceutil/trace.go:171","msg":"trace[1551177220] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"813.418196ms","start":"2026-04-20T06:07:17.808134Z","end":"2026-04-20T06:07:18.621552Z","steps":["trace[1551177220] 'process raft request' (duration: 813.207682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:18.621739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:17.808112Z","time spent":"813.546598ms","remote":"127.0.0.1:39328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:07:18.765524Z","caller":"traceutil/trace.go:171","msg":"trace[1017998318] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"884.142034ms","start":"2026-04-20T06:07:17.881358Z","end":"2026-04-20T06:07:18.7655Z","steps":["trace[1017998318] 'process raft request' (duration: 848.560417ms)","trace[1017998318] 'compare' (duration: 35.429824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:07:18.765665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:17.881325Z","time spent":"884.268337ms","remote":"127.0.0.1:59682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T06:07:18.765811Z","caller":"traceutil/trace.go:171","msg":"trace[830007637] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"138.616924ms","start":"2026-04-20T06:07:18.627171Z","end":"2026-04-20T06:07:18.765788Z","steps":["trace[830007637] 'process raft request' (duration: 138.503401ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:18.76583Z","caller":"traceutil/trace.go:171","msg":"trace[1343732540] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"155.464725ms","start":"2026-04-20T06:07:18.610326Z","end":"2026-04-20T06:07:18.76579Z","steps":["trace[1343732540] 'process raft request' (duration: 155.129628ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:18.765911Z","caller":"traceutil/trace.go:171","msg":"trace[888974081] linearizableReadLoop","detail":"{readStateIndex:8878; appliedIndex:8876; }","duration":"814.457486ms","start":"2026-04-20T06:07:17.951435Z","end":"2026-04-20T06:07:18.765893Z","steps":["trace[888974081] 'read index received' (duration: 669.901162ms)","trace[888974081] 'applied index is now lower than readState.Index' (duration: 144.555174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T06:07:18.76606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.313539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T06:07:18.766061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.612448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T06:07:18.766098Z","caller":"traceutil/trace.go:171","msg":"trace[213086523] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8578; }","duration":"684.398859ms","start":"2026-04-20T06:07:18.08169Z","end":"2026-04-20T06:07:18.766089Z","steps":["trace[213086523] 'agreement among raft nodes before linearized reading' (duration: 684.317928ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:18.766123Z","caller":"traceutil/trace.go:171","msg":"trace[284054596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8578; }","duration":"814.739571ms","start":"2026-04-20T06:07:17.951368Z","end":"2026-04-20T06:07:18.766108Z","steps":["trace[284054596] 'agreement among raft nodes before linearized reading' (duration: 814.623899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:18.766171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:17.951347Z","time spent":"814.816602ms","remote":"127.0.0.1:59424","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-04-20T06:07:18.766235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.651168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T06:07:18.766272Z","caller":"traceutil/trace.go:171","msg":"trace[1544561462] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8578; }","duration":"269.72567ms","start":"2026-04-20T06:07:18.496538Z","end":"2026-04-20T06:07:18.766264Z","steps":["trace[1544561462] 'agreement among raft nodes before linearized reading' (duration: 269.611658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:18.766131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T06:07:18.081668Z","time spent":"684.455061ms","remote":"127.0.0.1:59852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-20T06:07:18.766329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.575936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-04-20T06:07:18.766413Z","caller":"traceutil/trace.go:171","msg":"trace[2144711830] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8578; }","duration":"136.703029ms","start":"2026-04-20T06:07:18.62969Z","end":"2026-04-20T06:07:18.766393Z","steps":["trace[2144711830] 'agreement among raft nodes before linearized reading' (duration: 136.449374ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:31.651513Z","caller":"traceutil/trace.go:171","msg":"trace[1932884721] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"110.224679ms","start":"2026-04-20T06:07:31.541268Z","end":"2026-04-20T06:07:31.651492Z","steps":["trace[1932884721] 'process raft request' (duration: 109.72888ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:33.181149Z","caller":"traceutil/trace.go:171","msg":"trace[896485685] linearizableReadLoop","detail":"{readStateIndex:9070; appliedIndex:9069; }","duration":"109.410644ms","start":"2026-04-20T06:07:33.07171Z","end":"2026-04-20T06:07:33.18112Z","steps":["trace[896485685] 'read index received' (duration: 92.144595ms)","trace[896485685] 'applied index is now lower than readState.Index' (duration: 17.265119ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:07:33.181273Z","caller":"traceutil/trace.go:171","msg":"trace[1771257881] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8762; }","duration":"111.93349ms","start":"2026-04-20T06:07:33.06933Z","end":"2026-04-20T06:07:33.181264Z","steps":["trace[1771257881] 'process raft request' (duration: 94.655991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:33.181684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.18763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/ceph-object-controller-detect-version-5mprx\" ","response":"range_response_count:1 size:2946"} {"level":"info","ts":"2026-04-20T06:07:33.181758Z","caller":"traceutil/trace.go:171","msg":"trace[1388483656] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/ceph-object-controller-detect-version-5mprx; range_end:; response_count:1; response_revision:8762; }","duration":"109.309762ms","start":"2026-04-20T06:07:33.07243Z","end":"2026-04-20T06:07:33.18174Z","steps":["trace[1388483656] 'agreement among raft nodes before linearized reading' (duration: 109.094608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:07:33.18178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.038466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-5mprx\" ","response":"range_response_count:1 size:4772"} {"level":"info","ts":"2026-04-20T06:07:33.181873Z","caller":"traceutil/trace.go:171","msg":"trace[2009864688] range","detail":"{range_begin:/registry/pods/openstack/ceph-object-controller-detect-version-5mprx; range_end:; response_count:1; response_revision:8762; }","duration":"110.175039ms","start":"2026-04-20T06:07:33.071675Z","end":"2026-04-20T06:07:33.18185Z","steps":["trace[2009864688] 'agreement among raft nodes before linearized reading' (duration: 109.909364ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:07:39.68631Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023bc.wal"} {"level":"info","ts":"2026-04-20T06:08:19.210027Z","caller":"traceutil/trace.go:171","msg":"trace[36426854] transaction","detail":"{read_only:false; response_revision:9229; number_of_response:1; }","duration":"182.727148ms","start":"2026-04-20T06:08:19.027274Z","end":"2026-04-20T06:08:19.210001Z","steps":["trace[36426854] 'process raft request' (duration: 182.561945ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:08:19.214679Z","caller":"traceutil/trace.go:171","msg":"trace[802898848] linearizableReadLoop","detail":"{readStateIndex:9548; appliedIndex:9547; }","duration":"116.649925ms","start":"2026-04-20T06:08:19.098004Z","end":"2026-04-20T06:08:19.214654Z","steps":["trace[802898848] 'read index received' (duration: 112.045258ms)","trace[802898848] 'applied index is now lower than readState.Index' (duration: 4.603497ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:08:19.214868Z","caller":"traceutil/trace.go:171","msg":"trace[1998268364] transaction","detail":"{read_only:false; response_revision:9230; number_of_response:1; }","duration":"179.215462ms","start":"2026-04-20T06:08:19.035578Z","end":"2026-04-20T06:08:19.214794Z","steps":["trace[1998268364] 'process raft request' (duration: 178.874316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:08:19.215164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.111334ms","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-04-20T06:08:19.215273Z","caller":"traceutil/trace.go:171","msg":"trace[1954618917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9230; }","duration":"117.307828ms","start":"2026-04-20T06:08:19.097945Z","end":"2026-04-20T06:08:19.215253Z","steps":["trace[1954618917] 'agreement among raft nodes before linearized reading' (duration: 116.95604ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:08:31.123415Z","caller":"traceutil/trace.go:171","msg":"trace[1024599276] transaction","detail":"{read_only:false; response_revision:9305; number_of_response:1; }","duration":"104.320983ms","start":"2026-04-20T06:08:31.019068Z","end":"2026-04-20T06:08:31.123389Z","steps":["trace[1024599276] 'process raft request' (duration: 104.231691ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:08:31.751848Z","caller":"traceutil/trace.go:171","msg":"trace[942997037] linearizableReadLoop","detail":"{readStateIndex:9631; appliedIndex:9630; }","duration":"114.82561ms","start":"2026-04-20T06:08:31.636995Z","end":"2026-04-20T06:08:31.751821Z","steps":["trace[942997037] 'read index received' (duration: 114.627545ms)","trace[942997037] 'applied index is now lower than readState.Index' (duration: 196.775µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T06:08:31.751914Z","caller":"traceutil/trace.go:171","msg":"trace[808428054] transaction","detail":"{read_only:false; response_revision:9310; number_of_response:1; }","duration":"116.91559ms","start":"2026-04-20T06:08:31.634962Z","end":"2026-04-20T06:08:31.751878Z","steps":["trace[808428054] 'process raft request' (duration: 116.687165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T06:08:31.752084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.065604ms","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-04-20T06:08:31.752211Z","caller":"traceutil/trace.go:171","msg":"trace[74711397] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9310; }","duration":"115.217639ms","start":"2026-04-20T06:08:31.636978Z","end":"2026-04-20T06:08:31.752196Z","steps":["trace[74711397] 'agreement among raft nodes before linearized reading' (duration: 114.946534ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T06:09:22.723564Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T06:09:22.733296Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T06:09:22.733448Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T06:11:42.424313Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8289} {"level":"info","ts":"2026-04-20T06:11:42.511887Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8289,"took":"84.169425ms","hash":3945273401,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":24850432,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-20T06:11:42.511991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3945273401,"revision":8289,"compact-revision":6092} {"level":"info","ts":"2026-04-20T06:16:42.430415Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10685} {"level":"info","ts":"2026-04-20T06:16:42.509912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10685,"took":"77.207105ms","hash":1151700638,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":20467712,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-20T06:16:42.509968Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1151700638,"revision":10685,"compact-revision":8289}