{"level":"warn","ts":"2026-09-22T03:50:57.219512Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:50:57.219611Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.86:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.86:2380","--initial-cluster=instance=https://162.253.55.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.86: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-09-22T03:50:57.219677Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T03:50:57.219689Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-22T03:50:57.219708Z","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-09-22T03:50:57.220339Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"]} {"level":"info","ts":"2026-09-22T03:50:57.22041Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.86: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-09-22T03:50:57.223505Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.87908ms"} {"level":"info","ts":"2026-09-22T03:50:57.231346Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d01cde7b2aa18f95","cluster-id":"9e5d9cb67aed7785"} {"level":"info","ts":"2026-09-22T03:50:57.231422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T03:50:57.231443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 0"} {"level":"info","ts":"2026-09-22T03:50:57.231457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d01cde7b2aa18f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T03:50:57.231472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became follower at term 1"} {"level":"info","ts":"2026-09-22T03:50:57.231677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"warn","ts":"2026-09-22T03:50:57.23869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T03:50:57.240134Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T03:50:57.241402Z","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-09-22T03:50:57.243354Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d01cde7b2aa18f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T03:50:57.24348Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d01cde7b2aa18f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T03:50:57.243704Z","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-09-22T03:50:57.243867Z","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-09-22T03:50:57.243883Z","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-09-22T03:50:57.244714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 switched to configuration voters=(14996105479814483861)"} {"level":"info","ts":"2026-09-22T03:50:57.245259Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","added-peer-id":"d01cde7b2aa18f95","added-peer-peer-urls":["https://162.253.55.86:2380"]} {"level":"info","ts":"2026-09-22T03:50:57.246382Z","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-09-22T03:50:57.246497Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-22T03:50:57.246557Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.86:2380"} {"level":"info","ts":"2026-09-22T03:50:57.246593Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d01cde7b2aa18f95","initial-advertise-peer-urls":["https://162.253.55.86:2380"],"listen-peer-urls":["https://162.253.55.86:2380"],"advertise-client-urls":["https://162.253.55.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T03:50:57.246631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T03:50:58.032499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T03:50:58.032582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T03:50:58.03262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgPreVoteResp from d01cde7b2aa18f95 at term 1"} {"level":"info","ts":"2026-09-22T03:50:58.032657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became candidate at term 2"} {"level":"info","ts":"2026-09-22T03:50:58.03268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 received MsgVoteResp from d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-22T03:50:58.0327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d01cde7b2aa18f95 became leader at term 2"} {"level":"info","ts":"2026-09-22T03:50:58.032724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d01cde7b2aa18f95 elected leader d01cde7b2aa18f95 at term 2"} {"level":"info","ts":"2026-09-22T03:50:58.03409Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:50:58.035058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:50:58.035057Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d01cde7b2aa18f95","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.86:2379]}","request-path":"/0/members/d01cde7b2aa18f95/attributes","cluster-id":"9e5d9cb67aed7785","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T03:50:58.035142Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T03:50:58.035414Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T03:50:58.035456Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T03:50:58.038169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.86:2379"} {"level":"info","ts":"2026-09-22T03:50:58.038409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T03:50:58.03867Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e5d9cb67aed7785","local-member-id":"d01cde7b2aa18f95","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:50:58.038839Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:50:58.038875Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T03:51:01.182855Z","caller":"traceutil/trace.go:171","msg":"trace[1701572968] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"165.674281ms","start":"2026-09-22T03:51:01.017153Z","end":"2026-09-22T03:51:01.182827Z","steps":["trace[1701572968] 'process raft request' (duration: 144.166596ms)","trace[1701572968] 'compare' (duration: 21.363141ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:51:49.211525Z","caller":"traceutil/trace.go:171","msg":"trace[1785244533] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"156.34688ms","start":"2026-09-22T03:51:49.055144Z","end":"2026-09-22T03:51:49.211491Z","steps":["trace[1785244533] 'process raft request' (duration: 155.65286ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:49.530601Z","caller":"traceutil/trace.go:171","msg":"trace[468750557] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"283.661947ms","start":"2026-09-22T03:51:49.246915Z","end":"2026-09-22T03:51:49.530577Z","steps":["trace[468750557] 'process raft request' (duration: 283.474121ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:49.544039Z","caller":"traceutil/trace.go:171","msg":"trace[73753753] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:673; }","duration":"171.523262ms","start":"2026-09-22T03:51:49.372492Z","end":"2026-09-22T03:51:49.544015Z","steps":["trace[73753753] 'read index received' (duration: 158.006468ms)","trace[73753753] 'applied index is now lower than readState.Index' (duration: 13.515714ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:51:49.544189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.675726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:51:49.544294Z","caller":"traceutil/trace.go:171","msg":"trace[1339090543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"171.82213ms","start":"2026-09-22T03:51:49.372457Z","end":"2026-09-22T03:51:49.54428Z","steps":["trace[1339090543] 'agreement among raft nodes before linearized reading' (duration: 171.647695ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:51:49.544331Z","caller":"traceutil/trace.go:171","msg":"trace[632398069] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"243.184507ms","start":"2026-09-22T03:51:49.301073Z","end":"2026-09-22T03:51:49.544258Z","steps":["trace[632398069] 'process raft request' (duration: 242.841168ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:52:56.648977Z","caller":"traceutil/trace.go:171","msg":"trace[150992856] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"102.599364ms","start":"2026-09-22T03:52:56.546354Z","end":"2026-09-22T03:52:56.648954Z","steps":["trace[150992856] 'process raft request' (duration: 102.45614ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:52:56.657282Z","caller":"traceutil/trace.go:171","msg":"trace[1607847110] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"107.111912ms","start":"2026-09-22T03:52:56.550115Z","end":"2026-09-22T03:52:56.657227Z","steps":["trace[1607847110] 'process raft request' (duration: 106.762602ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:53:23.246476Z","caller":"traceutil/trace.go:171","msg":"trace[103016954] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"235.594821ms","start":"2026-09-22T03:53:23.010847Z","end":"2026-09-22T03:53:23.246442Z","steps":["trace[103016954] 'process raft request' (duration: 235.317713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:14.344165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.328897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-09-22T03:54:14.344271Z","caller":"traceutil/trace.go:171","msg":"trace[1776749437] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2213; }","duration":"189.473201ms","start":"2026-09-22T03:54:14.15477Z","end":"2026-09-22T03:54:14.344243Z","steps":["trace[1776749437] 'range keys from in-memory index tree' (duration: 189.149152ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:14.3443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.367086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:54:14.344352Z","caller":"traceutil/trace.go:171","msg":"trace[1434938120] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2213; }","duration":"152.642534ms","start":"2026-09-22T03:54:14.191693Z","end":"2026-09-22T03:54:14.344336Z","steps":["trace[1434938120] 'count revisions from in-memory index tree' (duration: 152.298534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:14.344584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.009098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-22T03:54:14.34469Z","caller":"traceutil/trace.go:171","msg":"trace[1908011197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2213; }","duration":"162.194023ms","start":"2026-09-22T03:54:14.182473Z","end":"2026-09-22T03:54:14.344667Z","steps":["trace[1908011197] 'range keys from in-memory index tree' (duration: 161.770471ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:14.47183Z","caller":"traceutil/trace.go:171","msg":"trace[1498275061] transaction","detail":"{read_only:false; response_revision:2214; number_of_response:1; }","duration":"271.410937ms","start":"2026-09-22T03:54:14.200393Z","end":"2026-09-22T03:54:14.471804Z","steps":["trace[1498275061] 'process raft request' (duration: 271.254253ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:14.501133Z","caller":"traceutil/trace.go:171","msg":"trace[1983023841] transaction","detail":"{read_only:false; response_revision:2215; number_of_response:1; }","duration":"152.379336ms","start":"2026-09-22T03:54:14.348722Z","end":"2026-09-22T03:54:14.501101Z","steps":["trace[1983023841] 'process raft request' (duration: 152.17332ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:14.501407Z","caller":"traceutil/trace.go:171","msg":"trace[821838707] linearizableReadLoop","detail":"{readStateIndex:2299; appliedIndex:2296; }","duration":"128.471207ms","start":"2026-09-22T03:54:14.372912Z","end":"2026-09-22T03:54:14.501384Z","steps":["trace[821838707] 'read index received' (duration: 98.860731ms)","trace[821838707] 'applied index is now lower than readState.Index' (duration: 29.609606ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T03:54:14.501437Z","caller":"traceutil/trace.go:171","msg":"trace[908728220] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"108.455402ms","start":"2026-09-22T03:54:14.392952Z","end":"2026-09-22T03:54:14.501407Z","steps":["trace[908728220] 'process raft request' (duration: 108.324058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:54:14.501549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.620591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T03:54:14.501586Z","caller":"traceutil/trace.go:171","msg":"trace[1414668141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2217; }","duration":"128.702674ms","start":"2026-09-22T03:54:14.372873Z","end":"2026-09-22T03:54:14.501576Z","steps":["trace[1414668141] 'agreement among raft nodes before linearized reading' (duration: 128.599011ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:54:14.501663Z","caller":"traceutil/trace.go:171","msg":"trace[1178583849] transaction","detail":"{read_only:false; response_revision:2216; number_of_response:1; }","duration":"152.470229ms","start":"2026-09-22T03:54:14.349176Z","end":"2026-09-22T03:54:14.501647Z","steps":["trace[1178583849] 'process raft request' (duration: 151.869142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:55:09.939227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.883134ms","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-09-22T03:55:09.939284Z","caller":"traceutil/trace.go:171","msg":"trace[565465648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2688; }","duration":"122.987987ms","start":"2026-09-22T03:55:09.816282Z","end":"2026-09-22T03:55:09.93927Z","steps":["trace[565465648] 'range keys from in-memory index tree' (duration: 122.731339ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T03:58:52.319708Z","caller":"traceutil/trace.go:171","msg":"trace[835221295] linearizableReadLoop","detail":"{readStateIndex:4238; appliedIndex:4237; }","duration":"141.309424ms","start":"2026-09-22T03:58:52.17837Z","end":"2026-09-22T03:58:52.319679Z","steps":["trace[835221295] 'read index received' (duration: 135.50133ms)","trace[835221295] 'applied index is now lower than readState.Index' (duration: 5.806714ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T03:58:52.319986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.649073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-22T03:58:52.320073Z","caller":"traceutil/trace.go:171","msg":"trace[1610425357] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4083; }","duration":"141.776886ms","start":"2026-09-22T03:58:52.178276Z","end":"2026-09-22T03:58:52.320053Z","steps":["trace[1610425357] 'agreement among raft nodes before linearized reading' (duration: 141.496078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T03:58:52.320218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.793573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qmjkg\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-22T03:58:52.320321Z","caller":"traceutil/trace.go:171","msg":"trace[1025174043] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qmjkg; range_end:; response_count:1; response_revision:4083; }","duration":"121.947597ms","start":"2026-09-22T03:58:52.198355Z","end":"2026-09-22T03:58:52.320303Z","steps":["trace[1025174043] 'agreement among raft nodes before linearized reading' (duration: 121.68263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:24.410908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.776887ms","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-09-22T04:00:24.410919Z","caller":"traceutil/trace.go:171","msg":"trace[121613429] linearizableReadLoop","detail":"{readStateIndex:5521; appliedIndex:5520; }","duration":"127.403144ms","start":"2026-09-22T04:00:24.283483Z","end":"2026-09-22T04:00:24.410886Z","steps":["trace[121613429] 'read index received' (duration: 122.538997ms)","trace[121613429] 'applied index is now lower than readState.Index' (duration: 4.862717ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:24.411049Z","caller":"traceutil/trace.go:171","msg":"trace[372079620] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5323; }","duration":"136.942192ms","start":"2026-09-22T04:00:24.274088Z","end":"2026-09-22T04:00:24.41103Z","steps":["trace[372079620] 'range keys from in-memory index tree' (duration: 136.758097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:24.41109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.581369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-739477f6-2fda-405d-a0b5-60a86c50203a\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-22T04:00:24.411176Z","caller":"traceutil/trace.go:171","msg":"trace[789072043] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-739477f6-2fda-405d-a0b5-60a86c50203a; range_end:; response_count:1; response_revision:5323; }","duration":"127.712723ms","start":"2026-09-22T04:00:24.283449Z","end":"2026-09-22T04:00:24.411162Z","steps":["trace[789072043] 'agreement among raft nodes before linearized reading' (duration: 127.477986ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:24.410957Z","caller":"traceutil/trace.go:171","msg":"trace[1200908878] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5323; }","duration":"130.880572ms","start":"2026-09-22T04:00:24.280044Z","end":"2026-09-22T04:00:24.410925Z","steps":["trace[1200908878] 'process raft request' (duration: 125.942463ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:24.582588Z","caller":"traceutil/trace.go:171","msg":"trace[1345746299] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"146.071199ms","start":"2026-09-22T04:00:24.436495Z","end":"2026-09-22T04:00:24.582567Z","steps":["trace[1345746299] 'process raft request' (duration: 145.971946ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:24.582964Z","caller":"traceutil/trace.go:171","msg":"trace[848443557] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"167.233634ms","start":"2026-09-22T04:00:24.415719Z","end":"2026-09-22T04:00:24.582952Z","steps":["trace[848443557] 'process raft request' (duration: 166.565425ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:24.623791Z","caller":"traceutil/trace.go:171","msg":"trace[149773814] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"108.792009ms","start":"2026-09-22T04:00:24.514972Z","end":"2026-09-22T04:00:24.623764Z","steps":["trace[149773814] 'process raft request' (duration: 108.648305ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:24.811741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.534094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T04:00:24.811989Z","caller":"traceutil/trace.go:171","msg":"trace[883424262] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"135.172222ms","start":"2026-09-22T04:00:24.676753Z","end":"2026-09-22T04:00:24.811925Z","steps":["trace[883424262] 'process raft request' (duration: 20.947639ms)","trace[883424262] 'compare' (duration: 113.263096ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:24.812144Z","caller":"traceutil/trace.go:171","msg":"trace[70490766] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"135.354268ms","start":"2026-09-22T04:00:24.67676Z","end":"2026-09-22T04:00:24.812114Z","steps":["trace[70490766] 'process raft request' (duration: 135.07687ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:24.812029Z","caller":"traceutil/trace.go:171","msg":"trace[1258914489] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"132.58749ms","start":"2026-09-22T04:00:24.679424Z","end":"2026-09-22T04:00:24.812011Z","steps":["trace[1258914489] 'process raft request' (duration: 132.499497ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:38.264192Z","caller":"traceutil/trace.go:171","msg":"trace[89922526] transaction","detail":"{read_only:false; response_revision:5583; number_of_response:1; }","duration":"140.739709ms","start":"2026-09-22T04:00:38.123411Z","end":"2026-09-22T04:00:38.264151Z","steps":["trace[89922526] 'process raft request' (duration: 140.372799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:48.10692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.489279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-22T04:00:48.107039Z","caller":"traceutil/trace.go:171","msg":"trace[249033387] linearizableReadLoop","detail":"{readStateIndex:5968; appliedIndex:5967; }","duration":"124.927754ms","start":"2026-09-22T04:00:47.982094Z","end":"2026-09-22T04:00:48.107022Z","steps":["trace[249033387] 'read index received' (duration: 10.965758ms)","trace[249033387] 'applied index is now lower than readState.Index' (duration: 113.960426ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:48.107133Z","caller":"traceutil/trace.go:171","msg":"trace[487681700] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"128.101773ms","start":"2026-09-22T04:00:47.979016Z","end":"2026-09-22T04:00:48.107118Z","steps":["trace[487681700] 'process raft request' (duration: 23.332706ms)","trace[487681700] 'compare' (duration: 104.333314ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:00:48.107562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.438818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-088316bd-da3f-4799-b96f-260d7d68f181\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-22T04:00:48.107645Z","caller":"traceutil/trace.go:171","msg":"trace[1682312296] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-088316bd-da3f-4799-b96f-260d7d68f181; range_end:; response_count:1; response_revision:5760; }","duration":"125.538261ms","start":"2026-09-22T04:00:47.982087Z","end":"2026-09-22T04:00:48.107625Z","steps":["trace[1682312296] 'agreement among raft nodes before linearized reading' (duration: 125.281014ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:48.538569Z","caller":"traceutil/trace.go:171","msg":"trace[1153982424] linearizableReadLoop","detail":"{readStateIndex:5971; appliedIndex:5969; }","duration":"191.175708ms","start":"2026-09-22T04:00:48.347363Z","end":"2026-09-22T04:00:48.538539Z","steps":["trace[1153982424] 'read index received' (duration: 187.591927ms)","trace[1153982424] 'applied index is now lower than readState.Index' (duration: 3.582491ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:00:48.5386Z","caller":"traceutil/trace.go:171","msg":"trace[1822327037] transaction","detail":"{read_only:false; response_revision:5762; number_of_response:1; }","duration":"422.899906ms","start":"2026-09-22T04:00:48.115636Z","end":"2026-09-22T04:00:48.538536Z","steps":["trace[1822327037] 'process raft request' (duration: 419.345206ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:48.53885Z","caller":"traceutil/trace.go:171","msg":"trace[460498696] transaction","detail":"{read_only:false; response_revision:5763; number_of_response:1; }","duration":"421.457714ms","start":"2026-09-22T04:00:48.117355Z","end":"2026-09-22T04:00:48.538813Z","steps":["trace[460498696] 'process raft request' (duration: 420.907649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:48.53891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.2688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-22T04:00:48.538967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.437745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T04:00:48.538975Z","caller":"traceutil/trace.go:171","msg":"trace[2064742931] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5763; }","duration":"143.356613ms","start":"2026-09-22T04:00:48.395602Z","end":"2026-09-22T04:00:48.538959Z","steps":["trace[2064742931] 'agreement among raft nodes before linearized reading' (duration: 143.205998ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:48.539005Z","caller":"traceutil/trace.go:171","msg":"trace[1619131953] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5763; }","duration":"191.687362ms","start":"2026-09-22T04:00:48.347309Z","end":"2026-09-22T04:00:48.538997Z","steps":["trace[1619131953] 'agreement among raft nodes before linearized reading' (duration: 191.448825ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:00:48.539033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:00:48.115613Z","time spent":"423.08218ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:00:48.539139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:00:48.117346Z","time spent":"421.558628ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:00:50.16647Z","caller":"traceutil/trace.go:171","msg":"trace[819804360] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"101.793654ms","start":"2026-09-22T04:00:50.064642Z","end":"2026-09-22T04:00:50.166436Z","steps":["trace[819804360] 'process raft request' (duration: 101.477755ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:54.679125Z","caller":"traceutil/trace.go:171","msg":"trace[1086965786] transaction","detail":"{read_only:false; response_revision:5888; number_of_response:1; }","duration":"111.284ms","start":"2026-09-22T04:00:54.567813Z","end":"2026-09-22T04:00:54.679097Z","steps":["trace[1086965786] 'process raft request' (duration: 110.92252ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:00:58.066369Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2971} {"level":"info","ts":"2026-09-22T04:00:58.143456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2971,"took":"75.7451ms","hash":467403236,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":32481280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T04:00:58.143587Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":467403236,"revision":2971,"compact-revision":-1} {"level":"info","ts":"2026-09-22T04:05:58.073726Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5909} {"level":"info","ts":"2026-09-22T04:05:58.157486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5909,"took":"81.744482ms","hash":4232386771,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":26787840,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-22T04:05:58.15754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4232386771,"revision":5909,"compact-revision":2971} {"level":"info","ts":"2026-09-22T04:07:02.718746Z","caller":"traceutil/trace.go:171","msg":"trace[2131570764] linearizableReadLoop","detail":"{readStateIndex:8944; appliedIndex:8943; }","duration":"125.956042ms","start":"2026-09-22T04:07:02.592769Z","end":"2026-09-22T04:07:02.718725Z","steps":["trace[2131570764] 'read index received' (duration: 125.765527ms)","trace[2131570764] 'applied index is now lower than readState.Index' (duration: 189.585µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:07:02.718826Z","caller":"traceutil/trace.go:171","msg":"trace[441216654] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"162.609323ms","start":"2026-09-22T04:07:02.556184Z","end":"2026-09-22T04:07:02.718793Z","steps":["trace[441216654] 'process raft request' (duration: 162.411537ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:07:02.719014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.225089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T04:07:02.719084Z","caller":"traceutil/trace.go:171","msg":"trace[2089095129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8643; }","duration":"126.335602ms","start":"2026-09-22T04:07:02.592729Z","end":"2026-09-22T04:07:02.719064Z","steps":["trace[2089095129] 'agreement among raft nodes before linearized reading' (duration: 126.105216ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:13.862516Z","caller":"traceutil/trace.go:171","msg":"trace[1827079563] transaction","detail":"{read_only:false; response_revision:8703; number_of_response:1; }","duration":"217.347682ms","start":"2026-09-22T04:07:13.645138Z","end":"2026-09-22T04:07:13.862486Z","steps":["trace[1827079563] 'process raft request' (duration: 217.121635ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:14.172183Z","caller":"traceutil/trace.go:171","msg":"trace[650549688] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"163.5955ms","start":"2026-09-22T04:07:14.008565Z","end":"2026-09-22T04:07:14.17216Z","steps":["trace[650549688] 'process raft request' (duration: 163.458057ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:14.972678Z","caller":"traceutil/trace.go:171","msg":"trace[1831922892] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"227.842687ms","start":"2026-09-22T04:07:14.74481Z","end":"2026-09-22T04:07:14.972653Z","steps":["trace[1831922892] 'process raft request' (duration: 227.665832ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:15.000946Z","caller":"traceutil/trace.go:171","msg":"trace[2034263707] linearizableReadLoop","detail":"{readStateIndex:9010; appliedIndex:9008; }","duration":"201.128937ms","start":"2026-09-22T04:07:14.799796Z","end":"2026-09-22T04:07:15.000925Z","steps":["trace[2034263707] 'read index received' (duration: 172.727708ms)","trace[2034263707] 'applied index is now lower than readState.Index' (duration: 28.400249ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:07:15.001043Z","caller":"traceutil/trace.go:171","msg":"trace[381281399] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"229.423132ms","start":"2026-09-22T04:07:14.771612Z","end":"2026-09-22T04:07:15.001035Z","steps":["trace[381281399] 'process raft request' (duration: 229.197665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:07:15.001259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.674487ms","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-09-22T04:07:15.00132Z","caller":"traceutil/trace.go:171","msg":"trace[2093463558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8707; }","duration":"187.77691ms","start":"2026-09-22T04:07:14.813527Z","end":"2026-09-22T04:07:15.001304Z","steps":["trace[2093463558] 'agreement among raft nodes before linearized reading' (duration: 187.610196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:07:15.001397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.587169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T04:07:15.001429Z","caller":"traceutil/trace.go:171","msg":"trace[1388875181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8707; }","duration":"201.661252ms","start":"2026-09-22T04:07:14.79976Z","end":"2026-09-22T04:07:15.001422Z","steps":["trace[1388875181] 'agreement among raft nodes before linearized reading' (duration: 201.546258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:07:15.001517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.412355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-22T04:07:15.001538Z","caller":"traceutil/trace.go:171","msg":"trace[978959968] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8707; }","duration":"158.461807ms","start":"2026-09-22T04:07:14.84307Z","end":"2026-09-22T04:07:15.001532Z","steps":["trace[978959968] 'agreement among raft nodes before linearized reading' (duration: 158.411275ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:43.808742Z","caller":"traceutil/trace.go:171","msg":"trace[1225847454] transaction","detail":"{read_only:false; response_revision:8916; number_of_response:1; }","duration":"154.246028ms","start":"2026-09-22T04:07:43.654477Z","end":"2026-09-22T04:07:43.808723Z","steps":["trace[1225847454] 'process raft request' (duration: 154.130675ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:53.956377Z","caller":"traceutil/trace.go:171","msg":"trace[271123903] transaction","detail":"{read_only:false; response_revision:8970; number_of_response:1; }","duration":"107.040728ms","start":"2026-09-22T04:07:53.849307Z","end":"2026-09-22T04:07:53.956348Z","steps":["trace[271123903] 'process raft request' (duration: 106.861513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:07:54.240093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.333077ms","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-09-22T04:07:54.240181Z","caller":"traceutil/trace.go:171","msg":"trace[154799239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8970; }","duration":"146.468521ms","start":"2026-09-22T04:07:54.093689Z","end":"2026-09-22T04:07:54.240157Z","steps":["trace[154799239] 'range keys from in-memory index tree' (duration: 146.156732ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:57.271405Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002459.wal"} {"level":"warn","ts":"2026-09-22T04:07:57.585287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.810706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T04:07:57.585349Z","caller":"traceutil/trace.go:171","msg":"trace[796327494] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8998; }","duration":"154.927669ms","start":"2026-09-22T04:07:57.430404Z","end":"2026-09-22T04:07:57.585332Z","steps":["trace[796327494] 'count revisions from in-memory index tree' (duration: 154.712293ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:57.636099Z","caller":"traceutil/trace.go:171","msg":"trace[610329385] linearizableReadLoop","detail":"{readStateIndex:9313; appliedIndex:9312; }","duration":"144.152386ms","start":"2026-09-22T04:07:57.491905Z","end":"2026-09-22T04:07:57.636057Z","steps":["trace[610329385] 'read index received' (duration: 93.25759ms)","trace[610329385] 'applied index is now lower than readState.Index' (duration: 50.892326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:07:57.636402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.855404ms","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-09-22T04:07:57.636388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.452574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-22T04:07:57.636465Z","caller":"traceutil/trace.go:171","msg":"trace[437500683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8998; }","duration":"117.956687ms","start":"2026-09-22T04:07:57.518491Z","end":"2026-09-22T04:07:57.636447Z","steps":["trace[437500683] 'agreement among raft nodes before linearized reading' (duration: 117.750811ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:07:57.636492Z","caller":"traceutil/trace.go:171","msg":"trace[163530324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8998; }","duration":"144.617169ms","start":"2026-09-22T04:07:57.491848Z","end":"2026-09-22T04:07:57.636465Z","steps":["trace[163530324] 'agreement among raft nodes before linearized reading' (duration: 144.333011ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:08:59.551904Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d01cde7b2aa18f95","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T04:08:59.562152Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-22T04:08:59.562268Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-22T04:09:17.838897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.774766ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-22T04:09:17.839027Z","caller":"traceutil/trace.go:171","msg":"trace[1759466649] linearizableReadLoop","detail":"{readStateIndex:10135; appliedIndex:10134; }","duration":"224.303146ms","start":"2026-09-22T04:09:17.614702Z","end":"2026-09-22T04:09:17.839005Z","steps":["trace[1759466649] 'read index received' (duration: 58.151µs)","trace[1759466649] 'applied index is now lower than readState.Index' (duration: 224.243165ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:09:17.839165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.44921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T04:09:17.839263Z","caller":"traceutil/trace.go:171","msg":"trace[1087932505] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:9796; }","duration":"224.556873ms","start":"2026-09-22T04:09:17.614655Z","end":"2026-09-22T04:09:17.839212Z","steps":["trace[1087932505] 'agreement among raft nodes before linearized reading' (duration: 224.43508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:09:17.839263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.860335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T04:09:17.83932Z","caller":"traceutil/trace.go:171","msg":"trace[1666227145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9796; }","duration":"166.955868ms","start":"2026-09-22T04:09:17.67235Z","end":"2026-09-22T04:09:17.839306Z","steps":["trace[1666227145] 'agreement among raft nodes before linearized reading' (duration: 166.767853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:09:17.839337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.336972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-22T04:09:17.839412Z","caller":"traceutil/trace.go:171","msg":"trace[1464893081] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9796; }","duration":"132.428385ms","start":"2026-09-22T04:09:17.706965Z","end":"2026-09-22T04:09:17.839393Z","steps":["trace[1464893081] 'agreement among raft nodes before linearized reading' (duration: 132.180778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:09:17.839667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.341287ms","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-09-22T04:09:17.839774Z","caller":"traceutil/trace.go:171","msg":"trace[1690041096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9796; }","duration":"157.488702ms","start":"2026-09-22T04:09:17.682262Z","end":"2026-09-22T04:09:17.83975Z","steps":["trace[1690041096] 'agreement among raft nodes before linearized reading' (duration: 157.025289ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:09:17.953451Z","caller":"traceutil/trace.go:171","msg":"trace[219456269] transaction","detail":"{read_only:false; response_revision:9798; number_of_response:1; }","duration":"109.145699ms","start":"2026-09-22T04:09:17.844285Z","end":"2026-09-22T04:09:17.95343Z","steps":["trace[219456269] 'process raft request' (duration: 85.0894ms)","trace[219456269] 'compare' (duration: 23.912075ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:09:17.953697Z","caller":"traceutil/trace.go:171","msg":"trace[1864175415] transaction","detail":"{read_only:false; response_revision:9799; number_of_response:1; }","duration":"109.356724ms","start":"2026-09-22T04:09:17.844302Z","end":"2026-09-22T04:09:17.953659Z","steps":["trace[1864175415] 'process raft request' (duration: 109.088457ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:10:58.080763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8069} {"level":"info","ts":"2026-09-22T04:10:58.151932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8069,"took":"69.071688ms","hash":2202712913,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":28286976,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T04:10:58.151999Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2202712913,"revision":8069,"compact-revision":5909} {"level":"info","ts":"2026-09-22T04:12:45.321899Z","caller":"traceutil/trace.go:171","msg":"trace[562409903] linearizableReadLoop","detail":"{readStateIndex:11939; appliedIndex:11938; }","duration":"226.944061ms","start":"2026-09-22T04:12:45.094928Z","end":"2026-09-22T04:12:45.321872Z","steps":["trace[562409903] 'read index received' (duration: 226.773196ms)","trace[562409903] 'applied index is now lower than readState.Index' (duration: 169.865µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:12:45.321949Z","caller":"traceutil/trace.go:171","msg":"trace[206702165] transaction","detail":"{read_only:false; response_revision:11550; number_of_response:1; }","duration":"240.331068ms","start":"2026-09-22T04:12:45.081592Z","end":"2026-09-22T04:12:45.321923Z","steps":["trace[206702165] 'process raft request' (duration: 240.075121ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:12:45.322138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.201618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-22T04:12:45.322178Z","caller":"traceutil/trace.go:171","msg":"trace[1217861576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11550; }","duration":"227.29677ms","start":"2026-09-22T04:12:45.094869Z","end":"2026-09-22T04:12:45.322166Z","steps":["trace[1217861576] 'agreement among raft nodes before linearized reading' (duration: 227.146176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:12:45.32223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.863697ms","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-09-22T04:12:45.322428Z","caller":"traceutil/trace.go:171","msg":"trace[1756558743] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11550; }","duration":"222.001941ms","start":"2026-09-22T04:12:45.100308Z","end":"2026-09-22T04:12:45.32231Z","steps":["trace[1756558743] 'agreement among raft nodes before linearized reading' (duration: 221.753824ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:15:58.087845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10497} {"level":"info","ts":"2026-09-22T04:15:58.174059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10497,"took":"83.902376ms","hash":875845922,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":27807744,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T04:15:58.174133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":875845922,"revision":10497,"compact-revision":8069} {"level":"info","ts":"2026-09-22T04:18:52.808379Z","caller":"traceutil/trace.go:171","msg":"trace[1709152017] linearizableReadLoop","detail":"{readStateIndex:14515; appliedIndex:14514; }","duration":"111.115291ms","start":"2026-09-22T04:18:52.697237Z","end":"2026-09-22T04:18:52.808353Z","steps":["trace[1709152017] 'read index received' (duration: 110.947926ms)","trace[1709152017] 'applied index is now lower than readState.Index' (duration: 166.555µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:18:52.80875Z","caller":"traceutil/trace.go:171","msg":"trace[196889182] transaction","detail":"{read_only:false; response_revision:14038; number_of_response:1; }","duration":"140.871671ms","start":"2026-09-22T04:18:52.667865Z","end":"2026-09-22T04:18:52.808737Z","steps":["trace[196889182] 'process raft request' (duration: 140.337565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:18:52.809037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.776711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T04:18:52.809151Z","caller":"traceutil/trace.go:171","msg":"trace[1221723832] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:14038; }","duration":"111.908754ms","start":"2026-09-22T04:18:52.697224Z","end":"2026-09-22T04:18:52.809133Z","steps":["trace[1221723832] 'agreement among raft nodes before linearized reading' (duration: 111.725269ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:19:17.125837Z","caller":"traceutil/trace.go:171","msg":"trace[1635270949] linearizableReadLoop","detail":"{readStateIndex:14650; appliedIndex:14649; }","duration":"195.945811ms","start":"2026-09-22T04:19:16.92987Z","end":"2026-09-22T04:19:17.125816Z","steps":["trace[1635270949] 'read index received' (duration: 195.720215ms)","trace[1635270949] 'applied index is now lower than readState.Index' (duration: 224.766µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:19:17.126016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.136218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T04:19:17.126052Z","caller":"traceutil/trace.go:171","msg":"trace[863943399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14169; }","duration":"196.22365ms","start":"2026-09-22T04:19:16.929818Z","end":"2026-09-22T04:19:17.126042Z","steps":["trace[863943399] 'agreement among raft nodes before linearized reading' (duration: 196.095746ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:19:17.125999Z","caller":"traceutil/trace.go:171","msg":"trace[1915212686] transaction","detail":"{read_only:false; response_revision:14169; number_of_response:1; }","duration":"236.352119ms","start":"2026-09-22T04:19:16.889603Z","end":"2026-09-22T04:19:17.125955Z","steps":["trace[1915212686] 'process raft request' (duration: 236.072351ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:19:34.088749Z","caller":"traceutil/trace.go:171","msg":"trace[2126318528] transaction","detail":"{read_only:false; response_revision:14275; number_of_response:1; }","duration":"239.623935ms","start":"2026-09-22T04:19:33.849066Z","end":"2026-09-22T04:19:34.08869Z","steps":["trace[2126318528] 'process raft request' (duration: 239.419319ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:19:34.089147Z","caller":"traceutil/trace.go:171","msg":"trace[835112455] linearizableReadLoop","detail":"{readStateIndex:14762; appliedIndex:14762; }","duration":"199.388682ms","start":"2026-09-22T04:19:33.889733Z","end":"2026-09-22T04:19:34.089122Z","steps":["trace[835112455] 'read index received' (duration: 199.371142ms)","trace[835112455] 'applied index is now lower than readState.Index' (duration: 15.75µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:19:34.089333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.563097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T04:19:34.089388Z","caller":"traceutil/trace.go:171","msg":"trace[1573331889] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:14275; }","duration":"199.702321ms","start":"2026-09-22T04:19:33.889669Z","end":"2026-09-22T04:19:34.089371Z","steps":["trace[1573331889] 'agreement among raft nodes before linearized reading' (duration: 199.559297ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:20:58.095015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12809} {"level":"info","ts":"2026-09-22T04:20:58.181012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12809,"took":"83.544365ms","hash":3274970331,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T04:20:58.181099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3274970331,"revision":12809,"compact-revision":10497} {"level":"info","ts":"2026-09-22T04:21:26.792573Z","caller":"traceutil/trace.go:171","msg":"trace[515051544] transaction","detail":"{read_only:false; response_revision:15548; number_of_response:1; }","duration":"125.118926ms","start":"2026-09-22T04:21:26.667416Z","end":"2026-09-22T04:21:26.792535Z","steps":["trace[515051544] 'process raft request' (duration: 124.949441ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:21:27.777089Z","caller":"traceutil/trace.go:171","msg":"trace[1430437821] transaction","detail":"{read_only:false; response_revision:15549; number_of_response:1; }","duration":"114.051796ms","start":"2026-09-22T04:21:27.663011Z","end":"2026-09-22T04:21:27.777063Z","steps":["trace[1430437821] 'process raft request' (duration: 113.905262ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:21:27.955808Z","caller":"traceutil/trace.go:171","msg":"trace[1518430337] linearizableReadLoop","detail":"{readStateIndex:16077; appliedIndex:16076; }","duration":"165.488742ms","start":"2026-09-22T04:21:27.79026Z","end":"2026-09-22T04:21:27.955749Z","steps":["trace[1518430337] 'read index received' (duration: 134.60696ms)","trace[1518430337] 'applied index is now lower than readState.Index' (duration: 30.880432ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:21:27.95589Z","caller":"traceutil/trace.go:171","msg":"trace[1505390808] transaction","detail":"{read_only:false; response_revision:15550; number_of_response:1; }","duration":"290.99691ms","start":"2026-09-22T04:21:27.664845Z","end":"2026-09-22T04:21:27.955842Z","steps":["trace[1505390808] 'process raft request' (duration: 260.038465ms)","trace[1505390808] 'compare' (duration: 30.749809ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:21:27.956092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.811991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-22T04:21:27.956159Z","caller":"traceutil/trace.go:171","msg":"trace[2087895615] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15550; }","duration":"165.925486ms","start":"2026-09-22T04:21:27.790216Z","end":"2026-09-22T04:21:27.956141Z","steps":["trace[2087895615] 'agreement among raft nodes before linearized reading' (duration: 165.72324ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:25:58.10358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15395} {"level":"info","ts":"2026-09-22T04:25:58.210882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15395,"took":"104.30383ms","hash":2916432421,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":32145408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T04:25:58.210946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2916432421,"revision":15395,"compact-revision":12809} {"level":"info","ts":"2026-09-22T04:27:04.076158Z","caller":"traceutil/trace.go:171","msg":"trace[1354681125] transaction","detail":"{read_only:false; response_revision:18131; number_of_response:1; }","duration":"107.48695ms","start":"2026-09-22T04:27:03.968633Z","end":"2026-09-22T04:27:04.07612Z","steps":["trace[1354681125] 'process raft request' (duration: 64.409208ms)","trace[1354681125] 'compare' (duration: 42.866296ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:27:08.084054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.015049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:27:08.084187Z","caller":"traceutil/trace.go:171","msg":"trace[1542531054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18160; }","duration":"278.195195ms","start":"2026-09-22T04:27:07.805965Z","end":"2026-09-22T04:27:08.08416Z","steps":["trace[1542531054] 'range keys from in-memory index tree' (duration: 277.832185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:27:08.084663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.391682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T04:27:08.084739Z","caller":"traceutil/trace.go:171","msg":"trace[1655014456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18160; }","duration":"104.493676ms","start":"2026-09-22T04:27:07.980231Z","end":"2026-09-22T04:27:08.084725Z","steps":["trace[1655014456] 'range keys from in-memory index tree' (duration: 103.411265ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:29:27.946943Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d01cde7b2aa18f95","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T04:29:27.954787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-22T04:29:27.954903Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-22T04:30:58.11657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17568} {"level":"info","ts":"2026-09-22T04:30:58.239341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17568,"took":"117.554553ms","hash":224848559,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":35155968,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-22T04:30:58.23943Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":224848559,"revision":17568,"compact-revision":15395} {"level":"info","ts":"2026-09-22T04:32:20.00854Z","caller":"traceutil/trace.go:171","msg":"trace[594398450] transaction","detail":"{read_only:false; response_revision:20583; number_of_response:1; }","duration":"167.880812ms","start":"2026-09-22T04:32:19.840631Z","end":"2026-09-22T04:32:20.008511Z","steps":["trace[594398450] 'process raft request' (duration: 109.443514ms)","trace[594398450] 'compare' (duration: 58.214381ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:32:20.321915Z","caller":"traceutil/trace.go:171","msg":"trace[1230441884] transaction","detail":"{read_only:false; response_revision:20584; number_of_response:1; }","duration":"255.014878ms","start":"2026-09-22T04:32:20.066864Z","end":"2026-09-22T04:32:20.321879Z","steps":["trace[1230441884] 'process raft request' (duration: 254.701339ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:32:36.280721Z","caller":"traceutil/trace.go:171","msg":"trace[1387396797] transaction","detail":"{read_only:false; response_revision:20665; number_of_response:1; }","duration":"199.72034ms","start":"2026-09-22T04:32:36.080952Z","end":"2026-09-22T04:32:36.280673Z","steps":["trace[1387396797] 'process raft request' (duration: 199.469123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:32:36.473959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.078154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:32:36.474027Z","caller":"traceutil/trace.go:171","msg":"trace[765837159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20665; }","duration":"101.181998ms","start":"2026-09-22T04:32:36.37283Z","end":"2026-09-22T04:32:36.474012Z","steps":["trace[765837159] 'range keys from in-memory index tree' (duration: 101.005892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:32:36.718524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.011974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:32:36.718792Z","caller":"traceutil/trace.go:171","msg":"trace[1456800786] linearizableReadLoop","detail":"{readStateIndex:21361; appliedIndex:21360; }","duration":"248.858772ms","start":"2026-09-22T04:32:36.469912Z","end":"2026-09-22T04:32:36.718771Z","steps":["trace[1456800786] 'read index received' (duration: 26.751103ms)","trace[1456800786] 'applied index is now lower than readState.Index' (duration: 222.106439ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:32:36.719186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.264894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T04:32:36.718917Z","caller":"traceutil/trace.go:171","msg":"trace[1129731135] transaction","detail":"{read_only:false; response_revision:20667; number_of_response:1; }","duration":"269.554993ms","start":"2026-09-22T04:32:36.44933Z","end":"2026-09-22T04:32:36.718885Z","steps":["trace[1129731135] 'process raft request' (duration: 162.079916ms)","trace[1129731135] 'compare' (duration: 106.656384ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:32:36.719239Z","caller":"traceutil/trace.go:171","msg":"trace[1637821540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20668; }","duration":"249.380217ms","start":"2026-09-22T04:32:36.469848Z","end":"2026-09-22T04:32:36.719228Z","steps":["trace[1637821540] 'agreement among raft nodes before linearized reading' (duration: 249.191562ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:32:36.719006Z","caller":"traceutil/trace.go:171","msg":"trace[1013487866] transaction","detail":"{read_only:false; response_revision:20668; number_of_response:1; }","duration":"241.195434ms","start":"2026-09-22T04:32:36.477798Z","end":"2026-09-22T04:32:36.718994Z","steps":["trace[1013487866] 'process raft request' (duration: 240.854654ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:32:36.719512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.180059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:32:36.720314Z","caller":"traceutil/trace.go:171","msg":"trace[1332977066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20668; }","duration":"244.993742ms","start":"2026-09-22T04:32:36.475291Z","end":"2026-09-22T04:32:36.720285Z","steps":["trace[1332977066] 'agreement among raft nodes before linearized reading' (duration: 244.19745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:32:37.044808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.820695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:32:37.045007Z","caller":"traceutil/trace.go:171","msg":"trace[1573677245] transaction","detail":"{read_only:false; response_revision:20669; number_of_response:1; }","duration":"319.2047ms","start":"2026-09-22T04:32:36.725782Z","end":"2026-09-22T04:32:37.044987Z","steps":["trace[1573677245] 'process raft request' (duration: 148.132348ms)","trace[1573677245] 'compare' (duration: 170.604879ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:32:37.045129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:32:36.725762Z","time spent":"319.332394ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:35:58.126909Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20040} {"level":"info","ts":"2026-09-22T04:35:58.254207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20040,"took":"123.338612ms","hash":2963709326,"current-db-size-bytes":55808000,"current-db-size":"56 MB","current-db-size-in-use-bytes":30662656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T04:35:58.254271Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2963709326,"revision":20040,"compact-revision":17568} {"level":"warn","ts":"2026-09-22T04:40:03.705522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.913799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:40:03.70557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.0133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:40:03.705627Z","caller":"traceutil/trace.go:171","msg":"trace[1732375210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25246; }","duration":"334.058663ms","start":"2026-09-22T04:40:03.371544Z","end":"2026-09-22T04:40:03.705602Z","steps":["trace[1732375210] 'range keys from in-memory index tree' (duration: 333.783905ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:03.705648Z","caller":"traceutil/trace.go:171","msg":"trace[2025601898] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25246; }","duration":"427.147643ms","start":"2026-09-22T04:40:03.278479Z","end":"2026-09-22T04:40:03.705627Z","steps":["trace[2025601898] 'count revisions from in-memory index tree' (duration: 426.900396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:03.705668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.371523Z","time spent":"334.137225ms","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T04:40:03.70569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.278458Z","time spent":"427.220956ms","remote":"127.0.0.1:48174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:40:03.705786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.111683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:40:03.705881Z","caller":"traceutil/trace.go:171","msg":"trace[1919007531] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:25246; }","duration":"210.259077ms","start":"2026-09-22T04:40:03.495592Z","end":"2026-09-22T04:40:03.705851Z","steps":["trace[1919007531] 'count revisions from in-memory index tree' (duration: 209.995439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:03.705986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.615172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:03.706032Z","caller":"traceutil/trace.go:171","msg":"trace[1986860489] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25246; }","duration":"456.693545ms","start":"2026-09-22T04:40:03.249328Z","end":"2026-09-22T04:40:03.706021Z","steps":["trace[1986860489] 'count revisions from in-memory index tree' (duration: 456.457398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:03.706005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.707041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-22T04:40:03.706064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.249311Z","time spent":"456.744976ms","remote":"127.0.0.1:59384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:40:03.706087Z","caller":"traceutil/trace.go:171","msg":"trace[64810953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25246; }","duration":"452.826985ms","start":"2026-09-22T04:40:03.253239Z","end":"2026-09-22T04:40:03.706066Z","steps":["trace[64810953] 'range keys from in-memory index tree' (duration: 451.94965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:03.706133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.25322Z","time spent":"452.898096ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-22T04:40:03.713894Z","caller":"traceutil/trace.go:171","msg":"trace[286073906] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"114.518901ms","start":"2026-09-22T04:40:03.59929Z","end":"2026-09-22T04:40:03.713809Z","steps":["trace[286073906] 'process raft request' (duration: 114.315876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.044511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.975293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:04.044736Z","caller":"traceutil/trace.go:171","msg":"trace[262890653] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"321.748942ms","start":"2026-09-22T04:40:03.72296Z","end":"2026-09-22T04:40:04.044709Z","steps":["trace[262890653] 'process raft request' (duration: 153.455799ms)","trace[262890653] 'compare' (duration: 167.274444ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:04.044805Z","caller":"traceutil/trace.go:171","msg":"trace[1098224901] linearizableReadLoop","detail":"{readStateIndex:26073; appliedIndex:26071; }","duration":"294.745133ms","start":"2026-09-22T04:40:03.750037Z","end":"2026-09-22T04:40:04.044782Z","steps":["trace[1098224901] 'read index received' (duration: 126.395259ms)","trace[1098224901] 'applied index is now lower than readState.Index' (duration: 168.348574ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:04.044902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.722923Z","time spent":"321.880375ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:04.045005Z","caller":"traceutil/trace.go:171","msg":"trace[435021794] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"321.900547ms","start":"2026-09-22T04:40:03.723094Z","end":"2026-09-22T04:40:04.044994Z","steps":["trace[435021794] 'process raft request' (duration: 321.553097ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.045058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.723081Z","time spent":"321.950358ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:04.045371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.358376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-575988d86-8pns4\" ","response":"range_response_count:1 size:8646"} {"level":"info","ts":"2026-09-22T04:40:04.045437Z","caller":"traceutil/trace.go:171","msg":"trace[1825535547] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-575988d86-8pns4; range_end:; response_count:1; response_revision:25250; }","duration":"311.50457ms","start":"2026-09-22T04:40:03.733916Z","end":"2026-09-22T04:40:04.04542Z","steps":["trace[1825535547] 'agreement among raft nodes before linearized reading' (duration: 311.306574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.045487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.73386Z","time spent":"311.614553ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":8670,"request content":"key:\"/registry/pods/openstack/magnum-api-575988d86-8pns4\" "} {"level":"warn","ts":"2026-09-22T04:40:04.342116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.544063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:04.342265Z","caller":"traceutil/trace.go:171","msg":"trace[1297774571] linearizableReadLoop","detail":"{readStateIndex:26074; appliedIndex:26073; }","duration":"297.375708ms","start":"2026-09-22T04:40:04.044856Z","end":"2026-09-22T04:40:04.342232Z","steps":["trace[1297774571] 'read index received' (duration: 137.334741ms)","trace[1297774571] 'applied index is now lower than readState.Index' (duration: 160.040027ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:04.342295Z","caller":"traceutil/trace.go:171","msg":"trace[687852021] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"505.038081ms","start":"2026-09-22T04:40:03.837221Z","end":"2026-09-22T04:40:04.342259Z","steps":["trace[687852021] 'process raft request' (duration: 345.040625ms)","trace[687852021] 'compare' (duration: 159.08496ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:04.342382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.868975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:04.342434Z","caller":"traceutil/trace.go:171","msg":"trace[1811580391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25251; }","duration":"590.970788ms","start":"2026-09-22T04:40:03.751452Z","end":"2026-09-22T04:40:04.342423Z","steps":["trace[1811580391] 'agreement among raft nodes before linearized reading' (duration: 590.878086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.342457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.751432Z","time spent":"591.02013ms","remote":"127.0.0.1:44214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:40:04.342466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:03.837155Z","time spent":"505.242556ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:04.418591Z","caller":"traceutil/trace.go:171","msg":"trace[749979958] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"371.089667ms","start":"2026-09-22T04:40:04.047471Z","end":"2026-09-22T04:40:04.418561Z","steps":["trace[749979958] 'process raft request' (duration: 370.877551ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:04.41857Z","caller":"traceutil/trace.go:171","msg":"trace[1719908299] transaction","detail":"{read_only:false; response_revision:25253; number_of_response:1; }","duration":"360.170186ms","start":"2026-09-22T04:40:04.058376Z","end":"2026-09-22T04:40:04.418546Z","steps":["trace[1719908299] 'process raft request' (duration: 360.105824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.418711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.047443Z","time spent":"371.2025ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:04.418837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.058315Z","time spent":"360.385532ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8444,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:04.603405Z","caller":"traceutil/trace.go:171","msg":"trace[555263128] linearizableReadLoop","detail":"{readStateIndex:26077; appliedIndex:26074; }","duration":"261.091425ms","start":"2026-09-22T04:40:04.342289Z","end":"2026-09-22T04:40:04.60338Z","steps":["trace[555263128] 'read index received' (duration: 76.092487ms)","trace[555263128] 'applied index is now lower than readState.Index' (duration: 184.997978ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:04.603539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.153773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:04.603558Z","caller":"traceutil/trace.go:171","msg":"trace[1979791828] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"318.016045ms","start":"2026-09-22T04:40:04.285518Z","end":"2026-09-22T04:40:04.603534Z","steps":["trace[1979791828] 'process raft request' (duration: 218.477471ms)","trace[1979791828] 'compare' (duration: 99.291637ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:04.603574Z","caller":"traceutil/trace.go:171","msg":"trace[693052671] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:25254; }","duration":"427.234535ms","start":"2026-09-22T04:40:04.176329Z","end":"2026-09-22T04:40:04.603563Z","steps":["trace[693052671] 'agreement among raft nodes before linearized reading' (duration: 427.157463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.60357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.313296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-09-22T04:40:04.603606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.176309Z","time spent":"427.287227ms","remote":"127.0.0.1:59394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"info","ts":"2026-09-22T04:40:04.603616Z","caller":"traceutil/trace.go:171","msg":"trace[1804389820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25254; }","duration":"356.396478ms","start":"2026-09-22T04:40:04.247207Z","end":"2026-09-22T04:40:04.603603Z","steps":["trace[1804389820] 'agreement among raft nodes before linearized reading' (duration: 356.246324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.603652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.247185Z","time spent":"356.45519ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-22T04:40:04.603653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.285495Z","time spent":"318.101058ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:04.604073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.828895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-22T04:40:04.604112Z","caller":"traceutil/trace.go:171","msg":"trace[952056150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25254; }","duration":"458.905317ms","start":"2026-09-22T04:40:04.145196Z","end":"2026-09-22T04:40:04.604101Z","steps":["trace[952056150] 'agreement among raft nodes before linearized reading' (duration: 458.790034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.604136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.145157Z","time spent":"458.972279ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-22T04:40:04.605583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.60746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T04:40:04.605681Z","caller":"traceutil/trace.go:171","msg":"trace[1638332531] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25254; }","duration":"450.732084ms","start":"2026-09-22T04:40:04.154924Z","end":"2026-09-22T04:40:04.605656Z","steps":["trace[1638332531] 'agreement among raft nodes before linearized reading' (duration: 448.996705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.60574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.154916Z","time spent":"450.811917ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-22T04:40:04.802095Z","caller":"traceutil/trace.go:171","msg":"trace[1522693871] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"376.751808ms","start":"2026-09-22T04:40:04.425316Z","end":"2026-09-22T04:40:04.802068Z","steps":["trace[1522693871] 'process raft request' (duration: 376.531582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.802296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.425292Z","time spent":"376.893892ms","remote":"127.0.0.1:54278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:04.802589Z","caller":"traceutil/trace.go:171","msg":"trace[1094982207] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"377.216472ms","start":"2026-09-22T04:40:04.425348Z","end":"2026-09-22T04:40:04.802564Z","steps":["trace[1094982207] 'process raft request' (duration: 376.657906ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:04.802651Z","caller":"traceutil/trace.go:171","msg":"trace[886867220] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"376.724888ms","start":"2026-09-22T04:40:04.425912Z","end":"2026-09-22T04:40:04.802637Z","steps":["trace[886867220] 'process raft request' (duration: 376.422419ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.802706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.42533Z","time spent":"377.309044ms","remote":"127.0.0.1:54232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1167,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:04.802713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.425896Z","time spent":"376.784179ms","remote":"127.0.0.1:54116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":652,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:04.802702Z","caller":"traceutil/trace.go:171","msg":"trace[1841557702] linearizableReadLoop","detail":"{readStateIndex:26082; appliedIndex:26077; }","duration":"199.208433ms","start":"2026-09-22T04:40:04.603463Z","end":"2026-09-22T04:40:04.802671Z","steps":["trace[1841557702] 'read index received' (duration: 198.40267ms)","trace[1841557702] 'applied index is now lower than readState.Index' (duration: 804.693µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:04.802948Z","caller":"traceutil/trace.go:171","msg":"trace[38357277] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"368.441271ms","start":"2026-09-22T04:40:04.4345Z","end":"2026-09-22T04:40:04.802941Z","steps":["trace[38357277] 'process raft request' (duration: 368.096391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.802987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.434492Z","time spent":"368.473252ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:04.803022Z","caller":"traceutil/trace.go:171","msg":"trace[218443709] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"373.463074ms","start":"2026-09-22T04:40:04.429547Z","end":"2026-09-22T04:40:04.80301Z","steps":["trace[218443709] 'process raft request' (duration: 372.97352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.803085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.429535Z","time spent":"373.513635ms","remote":"127.0.0.1:54452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7531,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:04.803222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.351035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:04.80326Z","caller":"traceutil/trace.go:171","msg":"trace[1291859808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25259; }","duration":"429.420927ms","start":"2026-09-22T04:40:04.373831Z","end":"2026-09-22T04:40:04.803252Z","steps":["trace[1291859808] 'agreement among raft nodes before linearized reading' (duration: 429.363886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.803279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.373801Z","time spent":"429.472849ms","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T04:40:04.803351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.698191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-575988d86-8pns4\" ","response":"range_response_count:1 size:8462"} {"level":"info","ts":"2026-09-22T04:40:04.803398Z","caller":"traceutil/trace.go:171","msg":"trace[1141033033] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-575988d86-8pns4; range_end:; response_count:1; response_revision:25259; }","duration":"373.768823ms","start":"2026-09-22T04:40:04.429616Z","end":"2026-09-22T04:40:04.803384Z","steps":["trace[1141033033] 'agreement among raft nodes before linearized reading' (duration: 373.523066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.803432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.429591Z","time spent":"373.832995ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":8486,"request content":"key:\"/registry/pods/openstack/magnum-api-575988d86-8pns4\" "} {"level":"info","ts":"2026-09-22T04:40:04.953357Z","caller":"traceutil/trace.go:171","msg":"trace[121000326] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"345.778536ms","start":"2026-09-22T04:40:04.607532Z","end":"2026-09-22T04:40:04.953311Z","steps":["trace[121000326] 'process raft request' (duration: 304.12897ms)","trace[121000326] 'compare' (duration: 41.039029ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:04.953386Z","caller":"traceutil/trace.go:171","msg":"trace[459629642] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"333.516567ms","start":"2026-09-22T04:40:04.619849Z","end":"2026-09-22T04:40:04.953365Z","steps":["trace[459629642] 'process raft request' (duration: 333.373082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.953503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.619832Z","time spent":"333.61089ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:04.953529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.607512Z","time spent":"345.927521ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:04.953651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.591616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T04:40:04.953763Z","caller":"traceutil/trace.go:171","msg":"trace[297458951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25261; }","duration":"224.75384ms","start":"2026-09-22T04:40:04.72898Z","end":"2026-09-22T04:40:04.953734Z","steps":["trace[297458951] 'agreement among raft nodes before linearized reading' (duration: 224.430601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.953754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.040468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T04:40:04.95384Z","caller":"traceutil/trace.go:171","msg":"trace[810457667] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25261; }","duration":"343.159431ms","start":"2026-09-22T04:40:04.61066Z","end":"2026-09-22T04:40:04.953819Z","steps":["trace[810457667] 'agreement among raft nodes before linearized reading' (duration: 342.882914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:04.953885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.610648Z","time spent":"343.228523ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-22T04:40:04.953346Z","caller":"traceutil/trace.go:171","msg":"trace[970490462] linearizableReadLoop","detail":"{readStateIndex:26084; appliedIndex:26082; }","duration":"150.536356ms","start":"2026-09-22T04:40:04.802788Z","end":"2026-09-22T04:40:04.953324Z","steps":["trace[970490462] 'read index received' (duration: 108.848659ms)","trace[970490462] 'applied index is now lower than readState.Index' (duration: 41.686467ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:05.212584Z","caller":"traceutil/trace.go:171","msg":"trace[92170867] linearizableReadLoop","detail":"{readStateIndex:26085; appliedIndex:26084; }","duration":"258.016127ms","start":"2026-09-22T04:40:04.954545Z","end":"2026-09-22T04:40:05.212561Z","steps":["trace[92170867] 'read index received' (duration: 205.43849ms)","trace[92170867] 'applied index is now lower than readState.Index' (duration: 52.576627ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:05.212827Z","caller":"traceutil/trace.go:171","msg":"trace[1552179947] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"398.916229ms","start":"2026-09-22T04:40:04.813895Z","end":"2026-09-22T04:40:05.212812Z","steps":["trace[1552179947] 'process raft request' (duration: 345.925401ms)","trace[1552179947] 'compare' (duration: 52.638368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:05.212927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.813878Z","time spent":"398.984301ms","remote":"127.0.0.1:54416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7834,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:05.213073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.249927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:05.21311Z","caller":"traceutil/trace.go:171","msg":"trace[387353301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25262; }","duration":"407.331529ms","start":"2026-09-22T04:40:04.805769Z","end":"2026-09-22T04:40:05.2131Z","steps":["trace[387353301] 'agreement among raft nodes before linearized reading' (duration: 407.274197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:05.213134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.805747Z","time spent":"407.38192ms","remote":"127.0.0.1:53886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T04:40:05.511798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.493414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:05.512048Z","caller":"traceutil/trace.go:171","msg":"trace[727545544] linearizableReadLoop","detail":"{readStateIndex:26089; appliedIndex:26085; }","duration":"299.389605ms","start":"2026-09-22T04:40:05.212642Z","end":"2026-09-22T04:40:05.512031Z","steps":["trace[727545544] 'read index received' (duration: 187.54923ms)","trace[727545544] 'applied index is now lower than readState.Index' (duration: 111.839425ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:05.512274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.000682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T04:40:05.512306Z","caller":"traceutil/trace.go:171","msg":"trace[1722492452] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25266; }","duration":"418.061614ms","start":"2026-09-22T04:40:05.09423Z","end":"2026-09-22T04:40:05.512292Z","steps":["trace[1722492452] 'agreement among raft nodes before linearized reading' (duration: 417.887249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:05.512283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.33712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:40:05.512335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:05.094218Z","time spent":"418.109805ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-22T04:40:05.512362Z","caller":"traceutil/trace.go:171","msg":"trace[331673100] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25266; }","duration":"262.452893ms","start":"2026-09-22T04:40:05.249887Z","end":"2026-09-22T04:40:05.51234Z","steps":["trace[331673100] 'agreement among raft nodes before linearized reading' (duration: 262.311389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:05.51255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.528974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T04:40:05.512569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.415753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19232"} {"level":"info","ts":"2026-09-22T04:40:05.512605Z","caller":"traceutil/trace.go:171","msg":"trace[782220492] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25266; }","duration":"119.624296ms","start":"2026-09-22T04:40:05.392962Z","end":"2026-09-22T04:40:05.512587Z","steps":["trace[782220492] 'agreement among raft nodes before linearized reading' (duration: 119.506863ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:05.51261Z","caller":"traceutil/trace.go:171","msg":"trace[371284150] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25266; }","duration":"557.482305ms","start":"2026-09-22T04:40:04.955115Z","end":"2026-09-22T04:40:05.512598Z","steps":["trace[371284150] 'agreement among raft nodes before linearized reading' (duration: 557.350031ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:05.512627Z","caller":"traceutil/trace.go:171","msg":"trace[312387615] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"553.674716ms","start":"2026-09-22T04:40:04.95894Z","end":"2026-09-22T04:40:05.512615Z","steps":["trace[312387615] 'process raft request' (duration: 441.278176ms)","trace[312387615] 'compare' (duration: 111.201536ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:05.512648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.955092Z","time spent":"557.549366ms","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19256,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-22T04:40:05.512708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:04.958911Z","time spent":"553.737237ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:05.512859Z","caller":"traceutil/trace.go:171","msg":"trace[1285539332] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"511.199637ms","start":"2026-09-22T04:40:05.001642Z","end":"2026-09-22T04:40:05.512842Z","steps":["trace[1285539332] 'process raft request' (duration: 510.24688ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:05.512924Z","caller":"traceutil/trace.go:171","msg":"trace[1039414663] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"492.171835ms","start":"2026-09-22T04:40:05.020744Z","end":"2026-09-22T04:40:05.512916Z","steps":["trace[1039414663] 'process raft request' (duration: 491.205557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:05.512937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:05.001621Z","time spent":"511.267388ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:05.512968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:05.020728Z","time spent":"492.213276ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:05.512234Z","caller":"traceutil/trace.go:171","msg":"trace[1958997841] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"440.582906ms","start":"2026-09-22T04:40:05.071575Z","end":"2026-09-22T04:40:05.512158Z","steps":["trace[1958997841] 'process raft request' (duration: 440.416762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:05.517523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:05.071559Z","time spent":"445.718882ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:05.71391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.966753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-09-22T04:40:05.714006Z","caller":"traceutil/trace.go:171","msg":"trace[697337821] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25267; }","duration":"102.126138ms","start":"2026-09-22T04:40:05.611858Z","end":"2026-09-22T04:40:05.713984Z","steps":["trace[697337821] 'range keys from in-memory index tree' (duration: 101.796119ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:05.870745Z","caller":"traceutil/trace.go:171","msg":"trace[648907198] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"111.963018ms","start":"2026-09-22T04:40:05.758755Z","end":"2026-09-22T04:40:05.870718Z","steps":["trace[648907198] 'process raft request' (duration: 111.809094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:06.322587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.433634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-22T04:40:06.322686Z","caller":"traceutil/trace.go:171","msg":"trace[2054689808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25268; }","duration":"274.555697ms","start":"2026-09-22T04:40:06.048085Z","end":"2026-09-22T04:40:06.322641Z","steps":["trace[2054689808] 'range keys from in-memory index tree' (duration: 274.231768ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:06.351004Z","caller":"traceutil/trace.go:171","msg":"trace[814399501] linearizableReadLoop","detail":"{readStateIndex:26092; appliedIndex:26091; }","duration":"294.65745ms","start":"2026-09-22T04:40:06.056317Z","end":"2026-09-22T04:40:06.350974Z","steps":["trace[814399501] 'read index received' (duration: 294.490265ms)","trace[814399501] 'applied index is now lower than readState.Index' (duration: 166.095µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:06.351069Z","caller":"traceutil/trace.go:171","msg":"trace[470669673] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"296.524423ms","start":"2026-09-22T04:40:06.054496Z","end":"2026-09-22T04:40:06.35102Z","steps":["trace[470669673] 'process raft request' (duration: 296.336488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:06.351142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.803223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:40:06.3512Z","caller":"traceutil/trace.go:171","msg":"trace[445980737] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25269; }","duration":"294.939978ms","start":"2026-09-22T04:40:06.056249Z","end":"2026-09-22T04:40:06.351189Z","steps":["trace[445980737] 'agreement among raft nodes before linearized reading' (duration: 294.823365ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:06.801327Z","caller":"traceutil/trace.go:171","msg":"trace[2074710074] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"475.244702ms","start":"2026-09-22T04:40:06.326045Z","end":"2026-09-22T04:40:06.80129Z","steps":["trace[2074710074] 'process raft request' (duration: 457.378994ms)","trace[2074710074] 'compare' (duration: 17.21092ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:06.80149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:06.326018Z","time spent":"475.389537ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:06.801546Z","caller":"traceutil/trace.go:171","msg":"trace[1175783608] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"454.08223ms","start":"2026-09-22T04:40:06.347442Z","end":"2026-09-22T04:40:06.801524Z","steps":["trace[1175783608] 'process raft request' (duration: 453.39491ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:06.80164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:06.347424Z","time spent":"454.165922ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:06.801709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.399479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:06.801751Z","caller":"traceutil/trace.go:171","msg":"trace[462437735] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"192.679086ms","start":"2026-09-22T04:40:06.609057Z","end":"2026-09-22T04:40:06.801736Z","steps":["trace[462437735] 'process raft request' (duration: 192.583143ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:06.801769Z","caller":"traceutil/trace.go:171","msg":"trace[740621301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25271; }","duration":"428.504281ms","start":"2026-09-22T04:40:06.37325Z","end":"2026-09-22T04:40:06.801754Z","steps":["trace[740621301] 'agreement among raft nodes before linearized reading' (duration: 428.403649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:06.801804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:06.373229Z","time spent":"428.568534ms","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-22T04:40:06.801956Z","caller":"traceutil/trace.go:171","msg":"trace[478921138] linearizableReadLoop","detail":"{readStateIndex:26094; appliedIndex:26092; }","duration":"428.584094ms","start":"2026-09-22T04:40:06.373303Z","end":"2026-09-22T04:40:06.801888Z","steps":["trace[478921138] 'read index received' (duration: 410.137049ms)","trace[478921138] 'applied index is now lower than readState.Index' (duration: 18.053364ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:06.802497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.347496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-22T04:40:06.802541Z","caller":"traceutil/trace.go:171","msg":"trace[2068386370] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25272; }","duration":"245.428578ms","start":"2026-09-22T04:40:06.5571Z","end":"2026-09-22T04:40:06.802529Z","steps":["trace[2068386370] 'agreement among raft nodes before linearized reading' (duration: 245.183361ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:10.916485Z","caller":"traceutil/trace.go:171","msg":"trace[1256097620] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"101.13243ms","start":"2026-09-22T04:40:10.81533Z","end":"2026-09-22T04:40:10.916462Z","steps":["trace[1256097620] 'process raft request' (duration: 100.498872ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:11.19676Z","caller":"traceutil/trace.go:171","msg":"trace[230550327] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"275.130794ms","start":"2026-09-22T04:40:10.921606Z","end":"2026-09-22T04:40:11.196737Z","steps":["trace[230550327] 'process raft request' (duration: 274.964489ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:11.196766Z","caller":"traceutil/trace.go:171","msg":"trace[2062234368] linearizableReadLoop","detail":"{readStateIndex:26132; appliedIndex:26131; }","duration":"222.266529ms","start":"2026-09-22T04:40:10.974467Z","end":"2026-09-22T04:40:11.196733Z","steps":["trace[2062234368] 'read index received' (duration: 222.252309ms)","trace[2062234368] 'applied index is now lower than readState.Index' (duration: 11.34µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:11.197023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.906538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T04:40:11.197052Z","caller":"traceutil/trace.go:171","msg":"trace[1392449384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25308; }","duration":"215.98681ms","start":"2026-09-22T04:40:10.981058Z","end":"2026-09-22T04:40:11.197045Z","steps":["trace[1392449384] 'agreement among raft nodes before linearized reading' (duration: 215.881747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:11.197054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.561947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:40:11.1971Z","caller":"traceutil/trace.go:171","msg":"trace[602314926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25308; }","duration":"222.687181ms","start":"2026-09-22T04:40:10.974398Z","end":"2026-09-22T04:40:11.197086Z","steps":["trace[602314926] 'agreement among raft nodes before linearized reading' (duration: 222.476625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:11.448261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.263908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:11.44857Z","caller":"traceutil/trace.go:171","msg":"trace[1265541512] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"247.387934ms","start":"2026-09-22T04:40:11.201146Z","end":"2026-09-22T04:40:11.448534Z","steps":["trace[1265541512] 'process raft request' (duration: 82.729045ms)","trace[1265541512] 'compare' (duration: 164.112323ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:11.448634Z","caller":"traceutil/trace.go:171","msg":"trace[1427314396] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"245.81264ms","start":"2026-09-22T04:40:11.202775Z","end":"2026-09-22T04:40:11.448587Z","steps":["trace[1427314396] 'process raft request' (duration: 245.575133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:11.756189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.649701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:11.756279Z","caller":"traceutil/trace.go:171","msg":"trace[536364677] linearizableReadLoop","detail":"{readStateIndex:26135; appliedIndex:26134; }","duration":"205.229164ms","start":"2026-09-22T04:40:11.551034Z","end":"2026-09-22T04:40:11.756263Z","steps":["trace[536364677] 'read index received' (duration: 81.519851ms)","trace[536364677] 'applied index is now lower than readState.Index' (duration: 123.708042ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:11.756483Z","caller":"traceutil/trace.go:171","msg":"trace[360097197] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"214.897669ms","start":"2026-09-22T04:40:11.541572Z","end":"2026-09-22T04:40:11.75647Z","steps":["trace[360097197] 'process raft request' (duration: 90.859087ms)","trace[360097197] 'compare' (duration: 123.447866ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:11.756744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.722838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T04:40:11.756781Z","caller":"traceutil/trace.go:171","msg":"trace[1456346438] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25311; }","duration":"205.81261ms","start":"2026-09-22T04:40:11.550958Z","end":"2026-09-22T04:40:11.756771Z","steps":["trace[1456346438] 'agreement among raft nodes before linearized reading' (duration: 205.687636ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:11.776939Z","caller":"traceutil/trace.go:171","msg":"trace[1201775301] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"225.842871ms","start":"2026-09-22T04:40:11.551081Z","end":"2026-09-22T04:40:11.776924Z","steps":["trace[1201775301] 'process raft request' (duration: 225.555853ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:11.777105Z","caller":"traceutil/trace.go:171","msg":"trace[91017461] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"224.648907ms","start":"2026-09-22T04:40:11.552447Z","end":"2026-09-22T04:40:11.777095Z","steps":["trace[91017461] 'process raft request' (duration: 224.274536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:11.777285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.848427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T04:40:11.777328Z","caller":"traceutil/trace.go:171","msg":"trace[958842803] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25313; }","duration":"221.917909ms","start":"2026-09-22T04:40:11.555398Z","end":"2026-09-22T04:40:11.777316Z","steps":["trace[958842803] 'agreement among raft nodes before linearized reading' (duration: 221.815726ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:11.932979Z","caller":"traceutil/trace.go:171","msg":"trace[1691967555] linearizableReadLoop","detail":"{readStateIndex:26138; appliedIndex:26137; }","duration":"156.190898ms","start":"2026-09-22T04:40:11.776759Z","end":"2026-09-22T04:40:11.93295Z","steps":["trace[1691967555] 'read index received' (duration: 155.326673ms)","trace[1691967555] 'applied index is now lower than readState.Index' (duration: 863.205µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:11.933082Z","caller":"traceutil/trace.go:171","msg":"trace[951280771] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"171.781021ms","start":"2026-09-22T04:40:11.761289Z","end":"2026-09-22T04:40:11.93307Z","steps":["trace[951280771] 'process raft request' (duration: 170.703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:11.933282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.164306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:11.933381Z","caller":"traceutil/trace.go:171","msg":"trace[1038521178] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25314; }","duration":"163.429003ms","start":"2026-09-22T04:40:11.769927Z","end":"2026-09-22T04:40:11.933356Z","steps":["trace[1038521178] 'agreement among raft nodes before linearized reading' (duration: 163.146205ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:11.953949Z","caller":"traceutil/trace.go:171","msg":"trace[467596030] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"172.335787ms","start":"2026-09-22T04:40:11.781588Z","end":"2026-09-22T04:40:11.953924Z","steps":["trace[467596030] 'process raft request' (duration: 172.134711ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:12.547936Z","caller":"traceutil/trace.go:171","msg":"trace[765948354] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"171.872554ms","start":"2026-09-22T04:40:12.37604Z","end":"2026-09-22T04:40:12.547913Z","steps":["trace[765948354] 'process raft request' (duration: 171.669228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:14.62541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.708174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-09-22T04:40:14.625593Z","caller":"traceutil/trace.go:171","msg":"trace[614446385] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25334; }","duration":"115.939082ms","start":"2026-09-22T04:40:14.509639Z","end":"2026-09-22T04:40:14.625578Z","steps":["trace[614446385] 'range keys from in-memory index tree' (duration: 115.586991ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:14.805354Z","caller":"traceutil/trace.go:171","msg":"trace[1415864881] transaction","detail":"{read_only:false; response_revision:25336; number_of_response:1; }","duration":"175.482337ms","start":"2026-09-22T04:40:14.629847Z","end":"2026-09-22T04:40:14.805329Z","steps":["trace[1415864881] 'process raft request' (duration: 174.869879ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:15.05889Z","caller":"traceutil/trace.go:171","msg":"trace[2077059964] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"139.112571ms","start":"2026-09-22T04:40:14.919742Z","end":"2026-09-22T04:40:15.058855Z","steps":["trace[2077059964] 'process raft request' (duration: 79.136863ms)","trace[2077059964] 'compare' (duration: 59.832484ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:15.065089Z","caller":"traceutil/trace.go:171","msg":"trace[1066133028] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"134.655575ms","start":"2026-09-22T04:40:14.930409Z","end":"2026-09-22T04:40:15.065065Z","steps":["trace[1066133028] 'process raft request' (duration: 134.604363ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:15.065129Z","caller":"traceutil/trace.go:171","msg":"trace[673220842] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"135.367475ms","start":"2026-09-22T04:40:14.929728Z","end":"2026-09-22T04:40:15.065096Z","steps":["trace[673220842] 'process raft request' (duration: 135.139918ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:25.828404Z","caller":"traceutil/trace.go:171","msg":"trace[105341242] linearizableReadLoop","detail":"{readStateIndex:26255; appliedIndex:26254; }","duration":"252.446939ms","start":"2026-09-22T04:40:25.575929Z","end":"2026-09-22T04:40:25.828375Z","steps":["trace[105341242] 'read index received' (duration: 252.373907ms)","trace[105341242] 'applied index is now lower than readState.Index' (duration: 71.302µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:25.828585Z","caller":"traceutil/trace.go:171","msg":"trace[917852330] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"273.410835ms","start":"2026-09-22T04:40:25.555121Z","end":"2026-09-22T04:40:25.828532Z","steps":["trace[917852330] 'process raft request' (duration: 273.061975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:25.828648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.623864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:25.828715Z","caller":"traceutil/trace.go:171","msg":"trace[514027218] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25428; }","duration":"252.776058ms","start":"2026-09-22T04:40:25.575922Z","end":"2026-09-22T04:40:25.828698Z","steps":["trace[514027218] 'agreement among raft nodes before linearized reading' (duration: 252.595393ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:27.230691Z","caller":"traceutil/trace.go:171","msg":"trace[1479322678] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"128.110228ms","start":"2026-09-22T04:40:27.102548Z","end":"2026-09-22T04:40:27.230658Z","steps":["trace[1479322678] 'process raft request' (duration: 127.938503ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:27.492055Z","caller":"traceutil/trace.go:171","msg":"trace[2036133468] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"389.253163ms","start":"2026-09-22T04:40:27.102772Z","end":"2026-09-22T04:40:27.492025Z","steps":["trace[2036133468] 'process raft request' (duration: 315.274207ms)","trace[2036133468] 'compare' (duration: 73.538934ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:27.492209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:27.102759Z","time spent":"389.362657ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:27.492259Z","caller":"traceutil/trace.go:171","msg":"trace[1146435774] linearizableReadLoop","detail":"{readStateIndex:26321; appliedIndex:26319; }","duration":"221.994991ms","start":"2026-09-22T04:40:27.270236Z","end":"2026-09-22T04:40:27.492231Z","steps":["trace[1146435774] 'read index received' (duration: 147.722857ms)","trace[1146435774] 'applied index is now lower than readState.Index' (duration: 74.270864ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:27.492296Z","caller":"traceutil/trace.go:171","msg":"trace[1104751868] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"388.268566ms","start":"2026-09-22T04:40:27.103986Z","end":"2026-09-22T04:40:27.492255Z","steps":["trace[1104751868] 'process raft request' (duration: 387.953207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:27.492494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.253319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-22T04:40:27.492508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:27.103976Z","time spent":"388.373819ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:27.492524Z","caller":"traceutil/trace.go:171","msg":"trace[2081930868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25494; }","duration":"222.351701ms","start":"2026-09-22T04:40:27.270163Z","end":"2026-09-22T04:40:27.492515Z","steps":["trace[2081930868] 'agreement among raft nodes before linearized reading' (duration: 222.213677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:27.492495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.370329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:27.492629Z","caller":"traceutil/trace.go:171","msg":"trace[1058678557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25494; }","duration":"119.520713ms","start":"2026-09-22T04:40:27.373059Z","end":"2026-09-22T04:40:27.49258Z","steps":["trace[1058678557] 'agreement among raft nodes before linearized reading' (duration: 119.338018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:27.797237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.411046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:27.797344Z","caller":"traceutil/trace.go:171","msg":"trace[946136896] linearizableReadLoop","detail":"{readStateIndex:26323; appliedIndex:26321; }","duration":"246.969301ms","start":"2026-09-22T04:40:27.550357Z","end":"2026-09-22T04:40:27.797326Z","steps":["trace[946136896] 'read index received' (duration: 28.016967ms)","trace[946136896] 'applied index is now lower than readState.Index' (duration: 218.950694ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:27.797433Z","caller":"traceutil/trace.go:171","msg":"trace[1240705505] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"283.042319ms","start":"2026-09-22T04:40:27.514353Z","end":"2026-09-22T04:40:27.797396Z","steps":["trace[1240705505] 'process raft request' (duration: 134.334454ms)","trace[1240705505] 'compare' (duration: 147.982834ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:27.797559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.198267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:40:27.7976Z","caller":"traceutil/trace.go:171","msg":"trace[1839856991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25496; }","duration":"247.29122ms","start":"2026-09-22T04:40:27.550294Z","end":"2026-09-22T04:40:27.797585Z","steps":["trace[1839856991] 'agreement among raft nodes before linearized reading' (duration: 247.118846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:27.797806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.839038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T04:40:27.797904Z","caller":"traceutil/trace.go:171","msg":"trace[2060964797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25496; }","duration":"246.971062ms","start":"2026-09-22T04:40:27.550908Z","end":"2026-09-22T04:40:27.797879Z","steps":["trace[2060964797] 'agreement among raft nodes before linearized reading' (duration: 246.623992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:27.797828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.971986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-09-22T04:40:27.798003Z","caller":"traceutil/trace.go:171","msg":"trace[1393919923] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9; range_end:; response_count:1; response_revision:25496; }","duration":"160.182151ms","start":"2026-09-22T04:40:27.637806Z","end":"2026-09-22T04:40:27.797989Z","steps":["trace[1393919923] 'agreement among raft nodes before linearized reading' (duration: 159.883822ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:28.124526Z","caller":"traceutil/trace.go:171","msg":"trace[852657570] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"289.297727ms","start":"2026-09-22T04:40:27.835205Z","end":"2026-09-22T04:40:28.124503Z","steps":["trace[852657570] 'process raft request' (duration: 289.115702ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:28.124816Z","caller":"traceutil/trace.go:171","msg":"trace[284055006] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"274.820225ms","start":"2026-09-22T04:40:27.849972Z","end":"2026-09-22T04:40:28.124793Z","steps":["trace[284055006] 'process raft request' (duration: 274.694292ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:28.124885Z","caller":"traceutil/trace.go:171","msg":"trace[1782679307] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"274.99972ms","start":"2026-09-22T04:40:27.849852Z","end":"2026-09-22T04:40:28.124852Z","steps":["trace[1782679307] 'process raft request' (duration: 274.608409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.558911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.860805ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-22T04:40:28.559076Z","caller":"traceutil/trace.go:171","msg":"trace[1769946096] linearizableReadLoop","detail":"{readStateIndex:26330; appliedIndex:26326; }","duration":"517.720472ms","start":"2026-09-22T04:40:28.04133Z","end":"2026-09-22T04:40:28.55905Z","steps":["trace[1769946096] 'read index received' (duration: 82.955292ms)","trace[1769946096] 'applied index is now lower than readState.Index' (duration: 434.76278ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:28.559451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.667154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:40:28.559506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.159905ms","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-09-22T04:40:28.559503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.655266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:28.559554Z","caller":"traceutil/trace.go:171","msg":"trace[612437244] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25502; }","duration":"518.248907ms","start":"2026-09-22T04:40:28.041287Z","end":"2026-09-22T04:40:28.559536Z","steps":["trace[612437244] 'agreement among raft nodes before linearized reading' (duration: 518.01659ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:28.559604Z","caller":"traceutil/trace.go:171","msg":"trace[1418042703] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25502; }","duration":"271.81638ms","start":"2026-09-22T04:40:28.287767Z","end":"2026-09-22T04:40:28.559584Z","steps":["trace[1418042703] 'agreement among raft nodes before linearized reading' (duration: 271.621895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.559635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:28.041269Z","time spent":"518.354831ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-22T04:40:28.559723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.632249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:28.559807Z","caller":"traceutil/trace.go:171","msg":"trace[593822840] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25502; }","duration":"104.765853ms","start":"2026-09-22T04:40:28.455021Z","end":"2026-09-22T04:40:28.559787Z","steps":["trace[593822840] 'agreement among raft nodes before linearized reading' (duration: 104.626959ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.559905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.681186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:28.559958Z","caller":"traceutil/trace.go:171","msg":"trace[1738839354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25502; }","duration":"186.79712ms","start":"2026-09-22T04:40:28.373151Z","end":"2026-09-22T04:40:28.559948Z","steps":["trace[1738839354] 'agreement among raft nodes before linearized reading' (duration: 186.720818ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:28.559532Z","caller":"traceutil/trace.go:171","msg":"trace[768738974] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25502; }","duration":"292.797637ms","start":"2026-09-22T04:40:28.266714Z","end":"2026-09-22T04:40:28.559511Z","steps":["trace[768738974] 'agreement among raft nodes before linearized reading' (duration: 292.684394ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.560133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.238404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9\" ","response":"range_response_count:1 size:3757"} {"level":"warn","ts":"2026-09-22T04:40:28.560138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.921671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:40:28.560206Z","caller":"traceutil/trace.go:171","msg":"trace[899432137] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9; range_end:; response_count:1; response_revision:25502; }","duration":"474.346637ms","start":"2026-09-22T04:40:28.085842Z","end":"2026-09-22T04:40:28.560188Z","steps":["trace[899432137] 'agreement among raft nodes before linearized reading' (duration: 473.613946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.560296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:28.08583Z","time spent":"474.45101ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3781,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9\" "} {"level":"info","ts":"2026-09-22T04:40:28.560221Z","caller":"traceutil/trace.go:171","msg":"trace[1279495002] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25502; }","duration":"201.074406ms","start":"2026-09-22T04:40:28.35913Z","end":"2026-09-22T04:40:28.560204Z","steps":["trace[1279495002] 'agreement among raft nodes before linearized reading' (duration: 200.970033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.560431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.891389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T04:40:28.560493Z","caller":"traceutil/trace.go:171","msg":"trace[1685630911] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25502; }","duration":"496.993112ms","start":"2026-09-22T04:40:28.063483Z","end":"2026-09-22T04:40:28.560477Z","steps":["trace[1685630911] 'agreement among raft nodes before linearized reading' (duration: 495.8726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.560526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:28.063461Z","time spent":"497.055594ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-22T04:40:28.560745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.823837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T04:40:28.560853Z","caller":"traceutil/trace.go:171","msg":"trace[1276434285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25502; }","duration":"483.981672ms","start":"2026-09-22T04:40:28.076845Z","end":"2026-09-22T04:40:28.560827Z","steps":["trace[1276434285] 'agreement among raft nodes before linearized reading' (duration: 482.689035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.561377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:28.076818Z","time spent":"484.536477ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-22T04:40:28.560692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.767493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-09-22T04:40:28.561605Z","caller":"traceutil/trace.go:171","msg":"trace[1064515829] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25502; }","duration":"472.70965ms","start":"2026-09-22T04:40:28.088877Z","end":"2026-09-22T04:40:28.561587Z","steps":["trace[1064515829] 'agreement among raft nodes before linearized reading' (duration: 470.618661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:28.561659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:28.088724Z","time spent":"472.918917ms","remote":"127.0.0.1:54144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-09-22T04:40:28.863837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.175539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:28.864043Z","caller":"traceutil/trace.go:171","msg":"trace[981489547] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"297.361427ms","start":"2026-09-22T04:40:28.566663Z","end":"2026-09-22T04:40:28.864025Z","steps":["trace[981489547] 'process raft request' (duration: 297.288145ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:28.864273Z","caller":"traceutil/trace.go:171","msg":"trace[438708764] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"298.484369ms","start":"2026-09-22T04:40:28.565715Z","end":"2026-09-22T04:40:28.864199Z","steps":["trace[438708764] 'process raft request' (duration: 175.845357ms)","trace[438708764] 'compare' (duration: 122.006564ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:29.233982Z","caller":"traceutil/trace.go:171","msg":"trace[1148235516] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"530.652111ms","start":"2026-09-22T04:40:28.703295Z","end":"2026-09-22T04:40:29.233947Z","steps":["trace[1148235516] 'process raft request' (duration: 488.534621ms)","trace[1148235516] 'compare' (duration: 41.729519ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:29.234122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:28.703271Z","time spent":"530.777834ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:29.254814Z","caller":"traceutil/trace.go:171","msg":"trace[1216469571] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"505.390411ms","start":"2026-09-22T04:40:28.749397Z","end":"2026-09-22T04:40:29.254788Z","steps":["trace[1216469571] 'process raft request' (duration: 505.263797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:29.254961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:28.74938Z","time spent":"505.500954ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:29.654725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.02005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-09-22T04:40:29.654815Z","caller":"traceutil/trace.go:171","msg":"trace[568921190] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25508; }","duration":"184.161574ms","start":"2026-09-22T04:40:29.47063Z","end":"2026-09-22T04:40:29.654792Z","steps":["trace[568921190] 'range keys from in-memory index tree' (duration: 183.816364ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:29.654839Z","caller":"traceutil/trace.go:171","msg":"trace[1914418174] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"156.896818ms","start":"2026-09-22T04:40:29.497914Z","end":"2026-09-22T04:40:29.654811Z","steps":["trace[1914418174] 'process raft request' (duration: 91.450614ms)","trace[1914418174] 'compare' (duration: 65.338451ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:29.673341Z","caller":"traceutil/trace.go:171","msg":"trace[412333331] linearizableReadLoop","detail":"{readStateIndex:26338; appliedIndex:26336; }","duration":"108.071577ms","start":"2026-09-22T04:40:29.565249Z","end":"2026-09-22T04:40:29.673321Z","steps":["trace[412333331] 'read index received' (duration: 24.128507ms)","trace[412333331] 'applied index is now lower than readState.Index' (duration: 83.94186ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:29.673411Z","caller":"traceutil/trace.go:171","msg":"trace[702463755] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"174.377395ms","start":"2026-09-22T04:40:29.498991Z","end":"2026-09-22T04:40:29.673368Z","steps":["trace[702463755] 'process raft request' (duration: 174.20704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:29.673538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.279243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-09-22T04:40:29.673577Z","caller":"traceutil/trace.go:171","msg":"trace[1630313811] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9; range_end:; response_count:1; response_revision:25510; }","duration":"108.366906ms","start":"2026-09-22T04:40:29.565199Z","end":"2026-09-22T04:40:29.673566Z","steps":["trace[1630313811] 'agreement among raft nodes before linearized reading' (duration: 108.225412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:29.972825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.008602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:29.973096Z","caller":"traceutil/trace.go:171","msg":"trace[1183852130] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"313.96173ms","start":"2026-09-22T04:40:29.659115Z","end":"2026-09-22T04:40:29.973077Z","steps":["trace[1183852130] 'process raft request' (duration: 157.523595ms)","trace[1183852130] 'compare' (duration: 155.635682ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:29.97315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.659089Z","time spent":"314.036252ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:29.973096Z","caller":"traceutil/trace.go:171","msg":"trace[484791928] linearizableReadLoop","detail":"{readStateIndex:26339; appliedIndex:26338; }","duration":"299.679134ms","start":"2026-09-22T04:40:29.673399Z","end":"2026-09-22T04:40:29.973078Z","steps":["trace[484791928] 'read index received' (duration: 143.206798ms)","trace[484791928] 'applied index is now lower than readState.Index' (duration: 156.469896ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:29.973144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.015187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:40:29.973222Z","caller":"traceutil/trace.go:171","msg":"trace[2074404099] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25511; }","duration":"363.098949ms","start":"2026-09-22T04:40:29.610106Z","end":"2026-09-22T04:40:29.973205Z","steps":["trace[2074404099] 'agreement among raft nodes before linearized reading' (duration: 362.973176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:29.973281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.610043Z","time spent":"363.221513ms","remote":"127.0.0.1:48182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:40:29.973314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.497768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-22T04:40:29.973366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.645265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-22T04:40:29.973384Z","caller":"traceutil/trace.go:171","msg":"trace[2052698974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25511; }","duration":"101.699916ms","start":"2026-09-22T04:40:29.871678Z","end":"2026-09-22T04:40:29.973378Z","steps":["trace[2052698974] 'agreement among raft nodes before linearized reading' (duration: 101.643215ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:29.973382Z","caller":"traceutil/trace.go:171","msg":"trace[1117863999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25511; }","duration":"391.56122ms","start":"2026-09-22T04:40:29.581799Z","end":"2026-09-22T04:40:29.97336Z","steps":["trace[1117863999] 'agreement among raft nodes before linearized reading' (duration: 391.367324ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:29.973446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.581737Z","time spent":"391.694734ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T04:40:29.97348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.614949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-09-22T04:40:29.973542Z","caller":"traceutil/trace.go:171","msg":"trace[463761112] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25511; }","duration":"163.707762ms","start":"2026-09-22T04:40:29.809816Z","end":"2026-09-22T04:40:29.973524Z","steps":["trace[463761112] 'agreement among raft nodes before linearized reading' (duration: 163.480805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:29.973546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.398711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-22T04:40:29.973628Z","caller":"traceutil/trace.go:171","msg":"trace[712950462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25511; }","duration":"138.545555ms","start":"2026-09-22T04:40:29.835062Z","end":"2026-09-22T04:40:29.973608Z","steps":["trace[712950462] 'agreement among raft nodes before linearized reading' (duration: 138.290338ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:30.312617Z","caller":"traceutil/trace.go:171","msg":"trace[735082231] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"332.861818ms","start":"2026-09-22T04:40:29.97973Z","end":"2026-09-22T04:40:30.312592Z","steps":["trace[735082231] 'process raft request' (duration: 284.629405ms)","trace[735082231] 'compare' (duration: 48.087419ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:30.312749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.979716Z","time spent":"332.962462ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:30.61957Z","caller":"traceutil/trace.go:171","msg":"trace[1931373140] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"639.398747ms","start":"2026-09-22T04:40:29.980131Z","end":"2026-09-22T04:40:30.61953Z","steps":["trace[1931373140] 'process raft request' (duration: 630.818293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.619716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.980126Z","time spent":"639.50407ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:30.619796Z","caller":"traceutil/trace.go:171","msg":"trace[1479789463] linearizableReadLoop","detail":"{readStateIndex:26343; appliedIndex:26339; }","duration":"633.135698ms","start":"2026-09-22T04:40:29.98661Z","end":"2026-09-22T04:40:30.619746Z","steps":["trace[1479789463] 'read index received' (duration: 277.737788ms)","trace[1479789463] 'applied index is now lower than readState.Index' (duration: 355.39688ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:30.619941Z","caller":"traceutil/trace.go:171","msg":"trace[738940650] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"490.497937ms","start":"2026-09-22T04:40:30.129424Z","end":"2026-09-22T04:40:30.619922Z","steps":["trace[738940650] 'process raft request' (duration: 490.177437ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:30.619944Z","caller":"traceutil/trace.go:171","msg":"trace[935309864] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"639.165361ms","start":"2026-09-22T04:40:29.98073Z","end":"2026-09-22T04:40:30.619895Z","steps":["trace[935309864] 'process raft request' (duration: 638.813641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.62007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:30.129401Z","time spent":"490.572739ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:30.620292Z","caller":"traceutil/trace.go:171","msg":"trace[605574431] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"490.525918ms","start":"2026-09-22T04:40:30.129749Z","end":"2026-09-22T04:40:30.620275Z","steps":["trace[605574431] 'process raft request' (duration: 489.90128ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.620384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:30.129739Z","time spent":"490.590799ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:30.620667Z","caller":"traceutil/trace.go:171","msg":"trace[216246802] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"490.864517ms","start":"2026-09-22T04:40:30.129793Z","end":"2026-09-22T04:40:30.620657Z","steps":["trace[216246802] 'process raft request' (duration: 489.90266ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.620755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:30.129775Z","time spent":"490.942529ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:30.620388Z","caller":"traceutil/trace.go:171","msg":"trace[256241078] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"640.015714ms","start":"2026-09-22T04:40:29.98036Z","end":"2026-09-22T04:40:30.620376Z","steps":["trace[256241078] 'process raft request' (duration: 639.052747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.620892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.980354Z","time spent":"640.485738ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:30.620839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.816524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-22T04:40:30.621004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.584698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:30.621053Z","caller":"traceutil/trace.go:171","msg":"trace[1731982555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25518; }","duration":"248.66444ms","start":"2026-09-22T04:40:30.372379Z","end":"2026-09-22T04:40:30.621044Z","steps":["trace[1731982555] 'agreement among raft nodes before linearized reading' (duration: 248.593948ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.62009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.980708Z","time spent":"639.304574ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:30.621142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.193339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:30.621236Z","caller":"traceutil/trace.go:171","msg":"trace[132226393] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25518; }","duration":"306.319742ms","start":"2026-09-22T04:40:30.314896Z","end":"2026-09-22T04:40:30.621215Z","steps":["trace[132226393] 'agreement among raft nodes before linearized reading' (duration: 306.159808ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:30.621057Z","caller":"traceutil/trace.go:171","msg":"trace[1670832040] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25518; }","duration":"394.062581ms","start":"2026-09-22T04:40:30.226971Z","end":"2026-09-22T04:40:30.621033Z","steps":["trace[1670832040] 'agreement among raft nodes before linearized reading' (duration: 393.795534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.6213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:30.314881Z","time spent":"306.407304ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:40:30.621384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:30.226957Z","time spent":"394.37671ms","remote":"127.0.0.1:38492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-09-22T04:40:30.621231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.529438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:30.622108Z","caller":"traceutil/trace.go:171","msg":"trace[1800852588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25518; }","duration":"635.342282ms","start":"2026-09-22T04:40:29.98659Z","end":"2026-09-22T04:40:30.621932Z","steps":["trace[1800852588] 'agreement among raft nodes before linearized reading' (duration: 634.108797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.622238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:29.986583Z","time spent":"635.63968ms","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T04:40:30.928488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.144398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:30.928615Z","caller":"traceutil/trace.go:171","msg":"trace[2110849367] linearizableReadLoop","detail":"{readStateIndex:26347; appliedIndex:26346; }","duration":"250.594886ms","start":"2026-09-22T04:40:30.677999Z","end":"2026-09-22T04:40:30.928594Z","steps":["trace[2110849367] 'read index received' (duration: 62.307724ms)","trace[2110849367] 'applied index is now lower than readState.Index' (duration: 188.284952ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:30.928947Z","caller":"traceutil/trace.go:171","msg":"trace[1058264461] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"302.865124ms","start":"2026-09-22T04:40:30.626062Z","end":"2026-09-22T04:40:30.928927Z","steps":["trace[1058264461] 'process raft request' (duration: 114.179952ms)","trace[1058264461] 'compare' (duration: 188.004293ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:30.929041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:30.625981Z","time spent":"303.010028ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:30.929096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.377526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:30.929247Z","caller":"traceutil/trace.go:171","msg":"trace[663692614] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25519; }","duration":"226.568002ms","start":"2026-09-22T04:40:30.702656Z","end":"2026-09-22T04:40:30.929224Z","steps":["trace[663692614] 'agreement among raft nodes before linearized reading' (duration: 226.379886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:30.929363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.348287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9\" ","response":"range_response_count:1 size:3757"} {"level":"info","ts":"2026-09-22T04:40:30.929424Z","caller":"traceutil/trace.go:171","msg":"trace[530975151] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a65fa14a-833e-404d-b8e3-a01d58ed38d9; range_end:; response_count:1; response_revision:25519; }","duration":"251.46074ms","start":"2026-09-22T04:40:30.677947Z","end":"2026-09-22T04:40:30.929408Z","steps":["trace[530975151] 'agreement among raft nodes before linearized reading' (duration: 251.078759ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:35.677114Z","caller":"traceutil/trace.go:171","msg":"trace[601256030] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"102.842648ms","start":"2026-09-22T04:40:35.57424Z","end":"2026-09-22T04:40:35.677083Z","steps":["trace[601256030] 'process raft request' (duration: 102.631072ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:51.425674Z","caller":"traceutil/trace.go:171","msg":"trace[667171719] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"123.2948ms","start":"2026-09-22T04:40:51.302351Z","end":"2026-09-22T04:40:51.425646Z","steps":["trace[667171719] 'process raft request' (duration: 77.918319ms)","trace[667171719] 'compare' (duration: 45.276528ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:51.511333Z","caller":"traceutil/trace.go:171","msg":"trace[731823732] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26531; }","duration":"138.861164ms","start":"2026-09-22T04:40:51.372449Z","end":"2026-09-22T04:40:51.51131Z","steps":["trace[731823732] 'read index received' (duration: 7.835433ms)","trace[731823732] 'applied index is now lower than readState.Index' (duration: 131.025001ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:51.511476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.013099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:51.51146Z","caller":"traceutil/trace.go:171","msg":"trace[1117239032] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"187.388306ms","start":"2026-09-22T04:40:51.324036Z","end":"2026-09-22T04:40:51.511424Z","steps":["trace[1117239032] 'process raft request' (duration: 187.15297ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:51.511507Z","caller":"traceutil/trace.go:171","msg":"trace[1728050857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25700; }","duration":"139.05907ms","start":"2026-09-22T04:40:51.372438Z","end":"2026-09-22T04:40:51.511497Z","steps":["trace[1728050857] 'agreement among raft nodes before linearized reading' (duration: 138.960077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:52.084951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.684893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:52.085052Z","caller":"traceutil/trace.go:171","msg":"trace[443191803] linearizableReadLoop","detail":"{readStateIndex:26535; appliedIndex:26533; }","duration":"343.409168ms","start":"2026-09-22T04:40:51.74162Z","end":"2026-09-22T04:40:52.08503Z","steps":["trace[443191803] 'read index received' (duration: 84.044403ms)","trace[443191803] 'applied index is now lower than readState.Index' (duration: 259.363705ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:52.085162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.547293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:52.085133Z","caller":"traceutil/trace.go:171","msg":"trace[1555031449] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"344.616733ms","start":"2026-09-22T04:40:51.740485Z","end":"2026-09-22T04:40:52.085102Z","steps":["trace[1555031449] 'process raft request' (duration: 221.683753ms)","trace[1555031449] 'compare' (duration: 122.586381ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:52.085215Z","caller":"traceutil/trace.go:171","msg":"trace[138602641] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25702; }","duration":"343.627125ms","start":"2026-09-22T04:40:51.741575Z","end":"2026-09-22T04:40:52.085203Z","steps":["trace[138602641] 'agreement among raft nodes before linearized reading' (duration: 343.551513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:52.085246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:51.741556Z","time spent":"343.682766ms","remote":"127.0.0.1:54416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-09-22T04:40:52.085306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:51.740445Z","time spent":"344.783718ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:52.370654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.80405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:52.370735Z","caller":"traceutil/trace.go:171","msg":"trace[1315876801] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25702; }","duration":"120.932843ms","start":"2026-09-22T04:40:52.249781Z","end":"2026-09-22T04:40:52.370714Z","steps":["trace[1315876801] 'count revisions from in-memory index tree' (duration: 120.719347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:52.670819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.778553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:52.670991Z","caller":"traceutil/trace.go:171","msg":"trace[317436106] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"232.376126ms","start":"2026-09-22T04:40:52.438598Z","end":"2026-09-22T04:40:52.670974Z","steps":["trace[317436106] 'process raft request' (duration: 232.308894ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:52.671137Z","caller":"traceutil/trace.go:171","msg":"trace[959065428] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"234.855097ms","start":"2026-09-22T04:40:52.436245Z","end":"2026-09-22T04:40:52.6711Z","steps":["trace[959065428] 'process raft request' (duration: 96.716144ms)","trace[959065428] 'compare' (duration: 137.603608ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:53.014425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.205014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:53.014536Z","caller":"traceutil/trace.go:171","msg":"trace[529739234] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"319.258281ms","start":"2026-09-22T04:40:52.695257Z","end":"2026-09-22T04:40:53.014515Z","steps":["trace[529739234] 'process raft request' (duration: 153.82273ms)","trace[529739234] 'compare' (duration: 164.70017ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:53.014602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:52.69524Z","time spent":"319.333073ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:53.086714Z","caller":"traceutil/trace.go:171","msg":"trace[1354362909] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26538; }","duration":"375.77944ms","start":"2026-09-22T04:40:52.710917Z","end":"2026-09-22T04:40:53.086697Z","steps":["trace[1354362909] 'read index received' (duration: 138.116582ms)","trace[1354362909] 'applied index is now lower than readState.Index' (duration: 237.662218ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:53.086769Z","caller":"traceutil/trace.go:171","msg":"trace[1974652162] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"388.363728ms","start":"2026-09-22T04:40:52.698376Z","end":"2026-09-22T04:40:53.086739Z","steps":["trace[1974652162] 'process raft request' (duration: 388.157843ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:53.086842Z","caller":"traceutil/trace.go:171","msg":"trace[1865464358] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"382.646816ms","start":"2026-09-22T04:40:52.704143Z","end":"2026-09-22T04:40:53.08679Z","steps":["trace[1865464358] 'process raft request' (duration: 382.523162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:53.086921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:52.698356Z","time spent":"388.474292ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:53.087026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:52.704129Z","time spent":"382.798821ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:40:53.087044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.477343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-22T04:40:53.086895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.970406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-22T04:40:53.087158Z","caller":"traceutil/trace.go:171","msg":"trace[1742422827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25707; }","duration":"368.607956ms","start":"2026-09-22T04:40:52.718502Z","end":"2026-09-22T04:40:53.08711Z","steps":["trace[1742422827] 'agreement among raft nodes before linearized reading' (duration: 368.357269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:53.087226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:52.718486Z","time spent":"368.73066ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-22T04:40:53.087225Z","caller":"traceutil/trace.go:171","msg":"trace[1815482641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25707; }","duration":"376.369237ms","start":"2026-09-22T04:40:52.710837Z","end":"2026-09-22T04:40:53.087206Z","steps":["trace[1815482641] 'agreement among raft nodes before linearized reading' (duration: 375.955965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:53.087278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:52.710816Z","time spent":"376.447929ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-22T04:40:53.303939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.029698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:40:53.30413Z","caller":"traceutil/trace.go:171","msg":"trace[65980484] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"213.676304ms","start":"2026-09-22T04:40:53.09043Z","end":"2026-09-22T04:40:53.304106Z","steps":["trace[65980484] 'process raft request' (duration: 73.366868ms)","trace[65980484] 'compare' (duration: 139.606826ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:53.304419Z","caller":"traceutil/trace.go:171","msg":"trace[743383142] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"212.952424ms","start":"2026-09-22T04:40:53.091443Z","end":"2026-09-22T04:40:53.304395Z","steps":["trace[743383142] 'process raft request' (duration: 212.589234ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:53.304498Z","caller":"traceutil/trace.go:171","msg":"trace[1815408647] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"212.612605ms","start":"2026-09-22T04:40:53.091868Z","end":"2026-09-22T04:40:53.30448Z","steps":["trace[1815408647] 'process raft request' (duration: 212.471291ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:53.304525Z","caller":"traceutil/trace.go:171","msg":"trace[228271819] linearizableReadLoop","detail":"{readStateIndex:26544; appliedIndex:26541; }","duration":"127.464569ms","start":"2026-09-22T04:40:53.177012Z","end":"2026-09-22T04:40:53.304477Z","steps":["trace[228271819] 'read index received' (duration: 60.825222ms)","trace[228271819] 'applied index is now lower than readState.Index' (duration: 66.638217ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:53.304698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.676736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-22T04:40:53.304765Z","caller":"traceutil/trace.go:171","msg":"trace[1361242110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25710; }","duration":"127.793649ms","start":"2026-09-22T04:40:53.176952Z","end":"2026-09-22T04:40:53.304746Z","steps":["trace[1361242110] 'agreement among raft nodes before linearized reading' (duration: 127.603974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:53.304797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.848399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-09-22T04:40:53.304835Z","caller":"traceutil/trace.go:171","msg":"trace[1912820018] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25710; }","duration":"102.933201ms","start":"2026-09-22T04:40:53.20189Z","end":"2026-09-22T04:40:53.304823Z","steps":["trace[1912820018] 'agreement among raft nodes before linearized reading' (duration: 102.780587ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:53.628985Z","caller":"traceutil/trace.go:171","msg":"trace[800597561] linearizableReadLoop","detail":"{readStateIndex:26546; appliedIndex:26545; }","duration":"257.191243ms","start":"2026-09-22T04:40:53.371769Z","end":"2026-09-22T04:40:53.62896Z","steps":["trace[800597561] 'read index received' (duration: 257.043489ms)","trace[800597561] 'applied index is now lower than readState.Index' (duration: 146.424µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:53.629126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.347758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:53.629108Z","caller":"traceutil/trace.go:171","msg":"trace[1011030782] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"320.141956ms","start":"2026-09-22T04:40:53.308934Z","end":"2026-09-22T04:40:53.629076Z","steps":["trace[1011030782] 'process raft request' (duration: 319.866228ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:53.629157Z","caller":"traceutil/trace.go:171","msg":"trace[382917400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25712; }","duration":"257.43153ms","start":"2026-09-22T04:40:53.371716Z","end":"2026-09-22T04:40:53.629147Z","steps":["trace[382917400] 'agreement among raft nodes before linearized reading' (duration: 257.361548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:53.629284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:40:53.308912Z","time spent":"320.29314ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:40:53.629722Z","caller":"traceutil/trace.go:171","msg":"trace[115071243] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"199.179571ms","start":"2026-09-22T04:40:53.430522Z","end":"2026-09-22T04:40:53.629701Z","steps":["trace[115071243] 'process raft request' (duration: 199.015496ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:53.629974Z","caller":"traceutil/trace.go:171","msg":"trace[1613430320] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"112.193015ms","start":"2026-09-22T04:40:53.517746Z","end":"2026-09-22T04:40:53.629939Z","steps":["trace[1613430320] 'process raft request' (duration: 111.916027ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:57.535247Z","caller":"traceutil/trace.go:171","msg":"trace[105592749] linearizableReadLoop","detail":"{readStateIndex:26588; appliedIndex:26587; }","duration":"152.631316ms","start":"2026-09-22T04:40:57.382592Z","end":"2026-09-22T04:40:57.535223Z","steps":["trace[105592749] 'read index received' (duration: 152.606445ms)","trace[105592749] 'applied index is now lower than readState.Index' (duration: 23.891µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:57.53541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.78592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:57.535487Z","caller":"traceutil/trace.go:171","msg":"trace[921898559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25754; }","duration":"152.917224ms","start":"2026-09-22T04:40:57.382552Z","end":"2026-09-22T04:40:57.535469Z","steps":["trace[921898559] 'agreement among raft nodes before linearized reading' (duration: 152.753259ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:57.535921Z","caller":"traceutil/trace.go:171","msg":"trace[1121313609] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"175.93162ms","start":"2026-09-22T04:40:57.359953Z","end":"2026-09-22T04:40:57.535884Z","steps":["trace[1121313609] 'process raft request' (duration: 175.143517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:40:57.668552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.561237ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-09-22T04:40:57.668639Z","caller":"traceutil/trace.go:171","msg":"trace[1429900110] linearizableReadLoop","detail":"{readStateIndex:26589; appliedIndex:26588; }","duration":"131.976758ms","start":"2026-09-22T04:40:57.536646Z","end":"2026-09-22T04:40:57.668623Z","steps":["trace[1429900110] 'read index received' (duration: 7.287487ms)","trace[1429900110] 'applied index is now lower than readState.Index' (duration: 124.688161ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:40:57.668731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.077451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:40:57.66875Z","caller":"traceutil/trace.go:171","msg":"trace[1909637993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25754; }","duration":"132.140363ms","start":"2026-09-22T04:40:57.536602Z","end":"2026-09-22T04:40:57.668743Z","steps":["trace[1909637993] 'agreement among raft nodes before linearized reading' (duration: 132.080671ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:40:58.421377Z","caller":"traceutil/trace.go:171","msg":"trace[20075563] compact","detail":"{revision:22312; response_revision:25760; }","duration":"224.513474ms","start":"2026-09-22T04:40:58.196837Z","end":"2026-09-22T04:40:58.421351Z","steps":["trace[20075563] 'process raft request' (duration: 127.578133ms)","trace[20075563] 'check and update compact revision' (duration: 96.828728ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:40:58.421637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22312} {"level":"info","ts":"2026-09-22T04:40:58.63187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22312,"took":"206.413398ms","hash":530530221,"current-db-size-bytes":56229888,"current-db-size":"56 MB","current-db-size-in-use-bytes":45051904,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-09-22T04:40:58.63192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":530530221,"revision":22312,"compact-revision":20040} {"level":"info","ts":"2026-09-22T04:41:02.840083Z","caller":"traceutil/trace.go:171","msg":"trace[1369117135] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"118.03466ms","start":"2026-09-22T04:41:02.722014Z","end":"2026-09-22T04:41:02.840049Z","steps":["trace[1369117135] 'process raft request' (duration: 117.814044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:03.687225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.511318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:41:03.687314Z","caller":"traceutil/trace.go:171","msg":"trace[96448122] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25798; }","duration":"116.689313ms","start":"2026-09-22T04:41:03.570605Z","end":"2026-09-22T04:41:03.687295Z","steps":["trace[96448122] 'count revisions from in-memory index tree' (duration: 116.414565ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:03.687336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.802412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-22T04:41:03.687407Z","caller":"traceutil/trace.go:171","msg":"trace[1330058388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25798; }","duration":"112.927655ms","start":"2026-09-22T04:41:03.574458Z","end":"2026-09-22T04:41:03.687385Z","steps":["trace[1330058388] 'range keys from in-memory index tree' (duration: 112.653228ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:03.835755Z","caller":"traceutil/trace.go:171","msg":"trace[2142825950] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"144.34356ms","start":"2026-09-22T04:41:03.691383Z","end":"2026-09-22T04:41:03.835727Z","steps":["trace[2142825950] 'process raft request' (duration: 144.173125ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:03.836049Z","caller":"traceutil/trace.go:171","msg":"trace[1457983714] linearizableReadLoop","detail":"{readStateIndex:26636; appliedIndex:26635; }","duration":"142.681633ms","start":"2026-09-22T04:41:03.693352Z","end":"2026-09-22T04:41:03.836034Z","steps":["trace[1457983714] 'read index received' (duration: 142.167628ms)","trace[1457983714] 'applied index is now lower than readState.Index' (duration: 512.605µs)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:41:03.836368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.93941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T04:41:03.836499Z","caller":"traceutil/trace.go:171","msg":"trace[1908920175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25799; }","duration":"143.143206ms","start":"2026-09-22T04:41:03.693314Z","end":"2026-09-22T04:41:03.836457Z","steps":["trace[1908920175] 'agreement among raft nodes before linearized reading' (duration: 142.812807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:04.041821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.399626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-22T04:41:04.042022Z","caller":"traceutil/trace.go:171","msg":"trace[1473754326] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"200.736486ms","start":"2026-09-22T04:41:03.841269Z","end":"2026-09-22T04:41:04.042006Z","steps":["trace[1473754326] 'process raft request' (duration: 200.699285ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:04.042056Z","caller":"traceutil/trace.go:171","msg":"trace[784472896] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"340.024703ms","start":"2026-09-22T04:41:03.701966Z","end":"2026-09-22T04:41:04.041991Z","steps":["trace[784472896] 'process raft request' (duration: 191.37521ms)","trace[784472896] 'compare' (duration: 148.025435ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:04.042201Z","caller":"traceutil/trace.go:171","msg":"trace[114368411] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"327.466345ms","start":"2026-09-22T04:41:03.7147Z","end":"2026-09-22T04:41:04.042166Z","steps":["trace[114368411] 'process raft request' (duration: 327.207258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:04.042271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:41:03.714677Z","time spent":"327.555388ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T04:41:04.042466Z","caller":"traceutil/trace.go:171","msg":"trace[1318426437] linearizableReadLoop","detail":"{readStateIndex:26638; appliedIndex:26636; }","duration":"206.319505ms","start":"2026-09-22T04:41:03.836132Z","end":"2026-09-22T04:41:04.042452Z","steps":["trace[1318426437] 'read index received' (duration: 57.190938ms)","trace[1318426437] 'applied index is now lower than readState.Index' (duration: 149.127467ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:41:04.042265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T04:41:03.701951Z","time spent":"340.187917ms","remote":"127.0.0.1:54220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T04:41:04.042713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.274795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-22T04:41:04.042766Z","caller":"traceutil/trace.go:171","msg":"trace[1649328804] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25802; }","duration":"271.382178ms","start":"2026-09-22T04:41:03.77137Z","end":"2026-09-22T04:41:04.042752Z","steps":["trace[1649328804] 'agreement among raft nodes before linearized reading' (duration: 271.222753ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:04.069579Z","caller":"traceutil/trace.go:171","msg":"trace[222241658] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"122.446527ms","start":"2026-09-22T04:41:03.947114Z","end":"2026-09-22T04:41:04.069561Z","steps":["trace[222241658] 'process raft request' (duration: 122.362895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:04.069728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.720607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-22T04:41:04.069782Z","caller":"traceutil/trace.go:171","msg":"trace[261760379] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25803; }","duration":"173.81318ms","start":"2026-09-22T04:41:03.895956Z","end":"2026-09-22T04:41:04.069769Z","steps":["trace[261760379] 'agreement among raft nodes before linearized reading' (duration: 173.641565ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:04.208844Z","caller":"traceutil/trace.go:171","msg":"trace[1868127201] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"160.644675ms","start":"2026-09-22T04:41:04.048162Z","end":"2026-09-22T04:41:04.208807Z","steps":["trace[1868127201] 'process raft request' (duration: 103.621141ms)","trace[1868127201] 'compare' (duration: 56.718995ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:04.336709Z","caller":"traceutil/trace.go:171","msg":"trace[247896132] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"129.458417ms","start":"2026-09-22T04:41:04.207217Z","end":"2026-09-22T04:41:04.336676Z","steps":["trace[247896132] 'process raft request' (duration: 129.299742ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:19.701406Z","caller":"traceutil/trace.go:171","msg":"trace[64552081] linearizableReadLoop","detail":"{readStateIndex:26755; appliedIndex:26754; }","duration":"122.766476ms","start":"2026-09-22T04:41:19.578616Z","end":"2026-09-22T04:41:19.701382Z","steps":["trace[64552081] 'read index received' (duration: 122.542859ms)","trace[64552081] 'applied index is now lower than readState.Index' (duration: 222.467µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:19.70146Z","caller":"traceutil/trace.go:171","msg":"trace[857562091] transaction","detail":"{read_only:false; response_revision:25915; number_of_response:1; }","duration":"132.124302ms","start":"2026-09-22T04:41:19.569303Z","end":"2026-09-22T04:41:19.701427Z","steps":["trace[857562091] 'process raft request' (duration: 131.935337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:19.701552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.92062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:41:19.701586Z","caller":"traceutil/trace.go:171","msg":"trace[991584603] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25915; }","duration":"123.014683ms","start":"2026-09-22T04:41:19.57856Z","end":"2026-09-22T04:41:19.701575Z","steps":["trace[991584603] 'agreement among raft nodes before linearized reading' (duration: 122.936151ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:20.214555Z","caller":"traceutil/trace.go:171","msg":"trace[116219108] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"115.925881ms","start":"2026-09-22T04:41:20.098603Z","end":"2026-09-22T04:41:20.214529Z","steps":["trace[116219108] 'process raft request' (duration: 77.27512ms)","trace[116219108] 'compare' (duration: 38.28366ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:20.214909Z","caller":"traceutil/trace.go:171","msg":"trace[833071511] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"115.116378ms","start":"2026-09-22T04:41:20.09978Z","end":"2026-09-22T04:41:20.214896Z","steps":["trace[833071511] 'process raft request' (duration: 114.660245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:20.418672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.750229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:41:20.418777Z","caller":"traceutil/trace.go:171","msg":"trace[474502814] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25920; }","duration":"100.874402ms","start":"2026-09-22T04:41:20.317881Z","end":"2026-09-22T04:41:20.418755Z","steps":["trace[474502814] 'count revisions from in-memory index tree' (duration: 100.645905ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:25.458391Z","caller":"traceutil/trace.go:171","msg":"trace[709518689] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"188.096096ms","start":"2026-09-22T04:41:25.270216Z","end":"2026-09-22T04:41:25.458312Z","steps":["trace[709518689] 'process raft request' (duration: 132.38843ms)","trace[709518689] 'compare' (duration: 55.50508ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:25.458472Z","caller":"traceutil/trace.go:171","msg":"trace[623152227] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"157.970448ms","start":"2026-09-22T04:41:25.300479Z","end":"2026-09-22T04:41:25.45845Z","steps":["trace[623152227] 'process raft request' (duration: 157.778693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:37.179731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.073838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/manila-ssh-keys\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:41:37.179835Z","caller":"traceutil/trace.go:171","msg":"trace[2130968817] range","detail":"{range_begin:/registry/secrets/openstack/manila-ssh-keys; range_end:; response_count:0; response_revision:26051; }","duration":"199.180791ms","start":"2026-09-22T04:41:36.980632Z","end":"2026-09-22T04:41:37.179813Z","steps":["trace[2130968817] 'range keys from in-memory index tree' (duration: 199.009957ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:37.440625Z","caller":"traceutil/trace.go:171","msg":"trace[2134276884] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"252.608533ms","start":"2026-09-22T04:41:37.187989Z","end":"2026-09-22T04:41:37.440597Z","steps":["trace[2134276884] 'process raft request' (duration: 244.862563ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:37.450398Z","caller":"traceutil/trace.go:171","msg":"trace[1548779973] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"159.574094ms","start":"2026-09-22T04:41:37.290789Z","end":"2026-09-22T04:41:37.450363Z","steps":["trace[1548779973] 'process raft request' (duration: 159.321476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:37.771774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.493759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-09-22T04:41:37.77185Z","caller":"traceutil/trace.go:171","msg":"trace[574111206] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:26056; }","duration":"128.591771ms","start":"2026-09-22T04:41:37.643237Z","end":"2026-09-22T04:41:37.771829Z","steps":["trace[574111206] 'range keys from in-memory index tree' (duration: 128.371885ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:37.93446Z","caller":"traceutil/trace.go:171","msg":"trace[1443226046] linearizableReadLoop","detail":"{readStateIndex:26902; appliedIndex:26901; }","duration":"124.059733ms","start":"2026-09-22T04:41:37.810369Z","end":"2026-09-22T04:41:37.934428Z","steps":["trace[1443226046] 'read index received' (duration: 119.714099ms)","trace[1443226046] 'applied index is now lower than readState.Index' (duration: 4.343914ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:37.93451Z","caller":"traceutil/trace.go:171","msg":"trace[109817088] transaction","detail":"{read_only:false; response_revision:26058; number_of_response:1; }","duration":"124.276379ms","start":"2026-09-22T04:41:37.810207Z","end":"2026-09-22T04:41:37.934484Z","steps":["trace[109817088] 'process raft request' (duration: 119.907504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:37.935799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.402201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-09-22T04:41:37.935891Z","caller":"traceutil/trace.go:171","msg":"trace[1245336928] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:26058; }","duration":"125.564695ms","start":"2026-09-22T04:41:37.810307Z","end":"2026-09-22T04:41:37.935872Z","steps":["trace[1245336928] 'agreement among raft nodes before linearized reading' (duration: 124.249598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:38.30572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.675574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T04:41:38.305978Z","caller":"traceutil/trace.go:171","msg":"trace[1737069516] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26058; }","duration":"187.973222ms","start":"2026-09-22T04:41:38.117988Z","end":"2026-09-22T04:41:38.305961Z","steps":["trace[1737069516] 'range keys from in-memory index tree' (duration: 187.52698ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:38.305987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.635689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:41:38.306075Z","caller":"traceutil/trace.go:171","msg":"trace[12793087] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26058; }","duration":"124.761352ms","start":"2026-09-22T04:41:38.181294Z","end":"2026-09-22T04:41:38.306056Z","steps":["trace[12793087] 'count revisions from in-memory index tree' (duration: 124.579218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:38.305773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.183068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-22T04:41:38.306207Z","caller":"traceutil/trace.go:171","msg":"trace[1246787875] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:26058; }","duration":"267.62389ms","start":"2026-09-22T04:41:38.038538Z","end":"2026-09-22T04:41:38.306162Z","steps":["trace[1246787875] 'range keys from in-memory index tree' (duration: 267.065094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:38.305864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.043357ms","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-09-22T04:41:38.306409Z","caller":"traceutil/trace.go:171","msg":"trace[487775100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26058; }","duration":"173.626054ms","start":"2026-09-22T04:41:38.13277Z","end":"2026-09-22T04:41:38.306396Z","steps":["trace[487775100] 'range keys from in-memory index tree' (duration: 172.908793ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:38.481037Z","caller":"traceutil/trace.go:171","msg":"trace[190104719] linearizableReadLoop","detail":"{readStateIndex:26905; appliedIndex:26902; }","duration":"171.712149ms","start":"2026-09-22T04:41:38.309303Z","end":"2026-09-22T04:41:38.481015Z","steps":["trace[190104719] 'read index received' (duration: 23.158409ms)","trace[190104719] 'applied index is now lower than readState.Index' (duration: 148.55275ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:38.48124Z","caller":"traceutil/trace.go:171","msg":"trace[584736116] transaction","detail":"{read_only:false; response_revision:26060; number_of_response:1; }","duration":"194.084256ms","start":"2026-09-22T04:41:38.287145Z","end":"2026-09-22T04:41:38.48123Z","steps":["trace[584736116] 'process raft request' (duration: 131.973438ms)","trace[584736116] 'compare' (duration: 61.698437ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:38.481348Z","caller":"traceutil/trace.go:171","msg":"trace[1397513630] transaction","detail":"{read_only:false; response_revision:26062; number_of_response:1; }","duration":"171.907795ms","start":"2026-09-22T04:41:38.309425Z","end":"2026-09-22T04:41:38.481333Z","steps":["trace[1397513630] 'process raft request' (duration: 171.551755ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:38.481447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.127832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:41:38.481488Z","caller":"traceutil/trace.go:171","msg":"trace[1503351950] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:26062; }","duration":"172.190053ms","start":"2026-09-22T04:41:38.309284Z","end":"2026-09-22T04:41:38.481474Z","steps":["trace[1503351950] 'agreement among raft nodes before linearized reading' (duration: 172.110431ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:38.481161Z","caller":"traceutil/trace.go:171","msg":"trace[939684755] transaction","detail":"{read_only:false; response_revision:26061; number_of_response:1; }","duration":"171.987038ms","start":"2026-09-22T04:41:38.309146Z","end":"2026-09-22T04:41:38.481133Z","steps":["trace[939684755] 'process raft request' (duration: 171.788802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:38.481689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.112941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-22T04:41:38.481741Z","caller":"traceutil/trace.go:171","msg":"trace[1711272047] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26062; }","duration":"172.184984ms","start":"2026-09-22T04:41:38.309542Z","end":"2026-09-22T04:41:38.481727Z","steps":["trace[1711272047] 'agreement among raft nodes before linearized reading' (duration: 172.024629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:38.481904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.108247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:41:38.48192Z","caller":"traceutil/trace.go:171","msg":"trace[2051558600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26062; }","duration":"109.157179ms","start":"2026-09-22T04:41:38.372758Z","end":"2026-09-22T04:41:38.481915Z","steps":["trace[2051558600] 'agreement among raft nodes before linearized reading' (duration: 109.128368ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:38.799976Z","caller":"traceutil/trace.go:171","msg":"trace[348347682] linearizableReadLoop","detail":"{readStateIndex:26910; appliedIndex:26909; }","duration":"108.916572ms","start":"2026-09-22T04:41:38.691035Z","end":"2026-09-22T04:41:38.799951Z","steps":["trace[348347682] 'read index received' (duration: 64.789905ms)","trace[348347682] 'applied index is now lower than readState.Index' (duration: 44.125187ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:38.800171Z","caller":"traceutil/trace.go:171","msg":"trace[1595429322] transaction","detail":"{read_only:false; response_revision:26066; number_of_response:1; }","duration":"141.687685ms","start":"2026-09-22T04:41:38.658467Z","end":"2026-09-22T04:41:38.800155Z","steps":["trace[1595429322] 'process raft request' (duration: 97.415274ms)","trace[1595429322] 'compare' (duration: 43.933721ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:41:38.800469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.419006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-22T04:41:38.800549Z","caller":"traceutil/trace.go:171","msg":"trace[1741259570] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26066; }","duration":"109.5458ms","start":"2026-09-22T04:41:38.690984Z","end":"2026-09-22T04:41:38.80053Z","steps":["trace[1741259570] 'agreement among raft nodes before linearized reading' (duration: 109.359574ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:39.389784Z","caller":"traceutil/trace.go:171","msg":"trace[1242873819] linearizableReadLoop","detail":"{readStateIndex:26911; appliedIndex:26910; }","duration":"121.464499ms","start":"2026-09-22T04:41:39.268286Z","end":"2026-09-22T04:41:39.38975Z","steps":["trace[1242873819] 'read index received' (duration: 121.137279ms)","trace[1242873819] 'applied index is now lower than readState.Index' (duration: 326.049µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:39.389844Z","caller":"traceutil/trace.go:171","msg":"trace[1768956694] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"124.189366ms","start":"2026-09-22T04:41:39.265626Z","end":"2026-09-22T04:41:39.389815Z","steps":["trace[1768956694] 'process raft request' (duration: 123.891138ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T04:41:39.390006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.669975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-22T04:41:39.39005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.17173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7698"} {"level":"info","ts":"2026-09-22T04:41:39.390076Z","caller":"traceutil/trace.go:171","msg":"trace[1867981786] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:26067; }","duration":"121.826809ms","start":"2026-09-22T04:41:39.26823Z","end":"2026-09-22T04:41:39.390057Z","steps":["trace[1867981786] 'agreement among raft nodes before linearized reading' (duration: 121.648104ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:39.390093Z","caller":"traceutil/trace.go:171","msg":"trace[370918002] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26067; }","duration":"102.239541ms","start":"2026-09-22T04:41:39.28784Z","end":"2026-09-22T04:41:39.39008Z","steps":["trace[370918002] 'agreement among raft nodes before linearized reading' (duration: 102.075786ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:39.581154Z","caller":"traceutil/trace.go:171","msg":"trace[980407263] transaction","detail":"{read_only:false; response_revision:26069; number_of_response:1; }","duration":"124.638779ms","start":"2026-09-22T04:41:39.456489Z","end":"2026-09-22T04:41:39.581128Z","steps":["trace[980407263] 'process raft request' (duration: 124.543296ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:39.581323Z","caller":"traceutil/trace.go:171","msg":"trace[1384574228] transaction","detail":"{read_only:false; response_revision:26068; number_of_response:1; }","duration":"187.745256ms","start":"2026-09-22T04:41:39.393545Z","end":"2026-09-22T04:41:39.58129Z","steps":["trace[1384574228] 'process raft request' (duration: 123.496986ms)","trace[1384574228] 'compare' (duration: 63.758966ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:41:39.58146Z","caller":"traceutil/trace.go:171","msg":"trace[936717410] linearizableReadLoop","detail":"{readStateIndex:26912; appliedIndex:26911; }","duration":"177.403081ms","start":"2026-09-22T04:41:39.404042Z","end":"2026-09-22T04:41:39.581445Z","steps":["trace[936717410] 'read index received' (duration: 113.016188ms)","trace[936717410] 'applied index is now lower than readState.Index' (duration: 64.384763ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:41:39.581542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.484644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:41:39.58157Z","caller":"traceutil/trace.go:171","msg":"trace[1449512887] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:26069; }","duration":"177.554305ms","start":"2026-09-22T04:41:39.404007Z","end":"2026-09-22T04:41:39.581561Z","steps":["trace[1449512887] 'agreement among raft nodes before linearized reading' (duration: 177.488314ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:41:41.082166Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006933.wal"} {"level":"info","ts":"2026-09-22T04:44:59.511841Z","caller":"traceutil/trace.go:171","msg":"trace[1500535854] linearizableReadLoop","detail":"{readStateIndex:29033; appliedIndex:29031; }","duration":"139.138781ms","start":"2026-09-22T04:44:59.372681Z","end":"2026-09-22T04:44:59.51182Z","steps":["trace[1500535854] 'read index received' (duration: 49.88818ms)","trace[1500535854] 'applied index is now lower than readState.Index' (duration: 89.249971ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T04:44:59.511942Z","caller":"traceutil/trace.go:171","msg":"trace[223962082] transaction","detail":"{read_only:false; response_revision:28137; number_of_response:1; }","duration":"150.368601ms","start":"2026-09-22T04:44:59.361556Z","end":"2026-09-22T04:44:59.511924Z","steps":["trace[223962082] 'process raft request' (duration: 118.097462ms)","trace[223962082] 'compare' (duration: 32.068674ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T04:44:59.511976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.283055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-22T04:44:59.511999Z","caller":"traceutil/trace.go:171","msg":"trace[1949138748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28137; }","duration":"139.345467ms","start":"2026-09-22T04:44:59.372646Z","end":"2026-09-22T04:44:59.511992Z","steps":["trace[1949138748] 'agreement among raft nodes before linearized reading' (duration: 139.257655ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T04:45:58.428354Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25760} {"level":"info","ts":"2026-09-22T04:45:58.589941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25760,"took":"157.559406ms","hash":1220819604,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":36139008,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-09-22T04:45:58.590005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1220819604,"revision":25760,"compact-revision":22312} {"level":"info","ts":"2026-09-22T04:46:58.901816Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d01cde7b2aa18f95","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T04:46:58.908473Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-22T04:46:58.908567Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-22T04:50:58.43599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28635} {"level":"info","ts":"2026-09-22T04:50:58.582645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28635,"took":"142.177319ms","hash":4005734291,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30986240,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T04:50:58.582708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4005734291,"revision":28635,"compact-revision":25760} {"level":"info","ts":"2026-09-22T04:55:58.442566Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30858} {"level":"info","ts":"2026-09-22T04:55:58.585242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30858,"took":"137.571452ms","hash":4148018801,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30437376,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-22T04:55:58.585299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4148018801,"revision":30858,"compact-revision":28635} {"level":"info","ts":"2026-09-22T05:00:58.449872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33116} {"level":"info","ts":"2026-09-22T05:00:58.605444Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33116,"took":"150.952087ms","hash":2898576977,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T05:00:58.605517Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2898576977,"revision":33116,"compact-revision":30858} {"level":"info","ts":"2026-09-22T05:05:58.45799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35461} {"level":"info","ts":"2026-09-22T05:05:58.6113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35461,"took":"149.057781ms","hash":1633620818,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":32296960,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-22T05:05:58.611369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1633620818,"revision":35461,"compact-revision":33116} {"level":"info","ts":"2026-09-22T05:08:18.559489Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d01cde7b2aa18f95","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-22T05:08:18.566052Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-22T05:08:18.566161Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-22T05:10:58.464675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37736} {"level":"info","ts":"2026-09-22T05:10:58.594923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37736,"took":"125.466026ms","hash":9080436,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":32985088,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-22T05:10:58.595016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":9080436,"revision":37736,"compact-revision":35461} {"level":"info","ts":"2026-09-22T05:15:58.471394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40011} {"level":"info","ts":"2026-09-22T05:15:58.599059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40011,"took":"123.28682ms","hash":2045136260,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30965760,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-22T05:15:58.599117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2045136260,"revision":40011,"compact-revision":37736}