{"level":"warn","ts":"2026-05-09T21:46:31.269367Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:46:31.269466Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T21:46:31.269543Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T21:46:31.269559Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-09T21:46:31.269589Z","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-05-09T21:46:31.270447Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-05-09T21:46:31.270544Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T21:46:31.275641Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.842384ms"} {"level":"info","ts":"2026-05-09T21:46:31.282138Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-05-09T21:46:31.282225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T21:46:31.282261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-05-09T21:46:31.282283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T21:46:31.282298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-05-09T21:46:31.282355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-05-09T21:46:31.287422Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T21:46:31.288689Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T21:46:31.289953Z","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-05-09T21:46:31.292092Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T21:46:31.292229Z","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-05-09T21:46:31.292345Z","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-05-09T21:46:31.292357Z","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-05-09T21:46:31.292337Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T21:46:31.293935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-05-09T21:46:31.294138Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-09T21:46:31.29724Z","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-05-09T21:46:31.297456Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-09T21:46:31.297553Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-09T21:46:31.297637Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T21:46:31.29768Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T21:46:31.783214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T21:46:31.78327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T21:46:31.783296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-05-09T21:46:31.783308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-05-09T21:46:31.783313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-09T21:46:31.783322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-05-09T21:46:31.783329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-09T21:46:31.792239Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:46:31.793319Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T21:46:31.793339Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:46:31.793478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T21:46:31.793703Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T21:46:31.793724Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T21:46:31.793841Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:46:31.793959Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:46:31.793988Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T21:46:31.796542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-05-09T21:46:31.79723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T21:46:57.716169Z","caller":"traceutil/trace.go:171","msg":"trace[2003752587] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"107.093096ms","start":"2026-05-09T21:46:57.60904Z","end":"2026-05-09T21:46:57.716133Z","steps":["trace[2003752587] 'process raft request' (duration: 106.78479ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:02.544603Z","caller":"traceutil/trace.go:171","msg":"trace[1969561762] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"103.015968ms","start":"2026-05-09T21:47:02.441551Z","end":"2026-05-09T21:47:02.544567Z","steps":["trace[1969561762] 'process raft request' (duration: 102.553159ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:02.544569Z","caller":"traceutil/trace.go:171","msg":"trace[862869917] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"103.219072ms","start":"2026-05-09T21:47:02.441325Z","end":"2026-05-09T21:47:02.544544Z","steps":["trace[862869917] 'process raft request' (duration: 102.59867ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:09.094409Z","caller":"traceutil/trace.go:171","msg":"trace[584662381] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"199.060471ms","start":"2026-05-09T21:47:08.895316Z","end":"2026-05-09T21:47:09.094377Z","steps":["trace[584662381] 'read index received' (duration: 198.778486ms)","trace[584662381] 'applied index is now lower than readState.Index' (duration: 280.625µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:47:09.094642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.315126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lrh9j\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-09T21:47:09.094714Z","caller":"traceutil/trace.go:171","msg":"trace[2071990136] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lrh9j; range_end:; response_count:1; response_revision:574; }","duration":"199.449228ms","start":"2026-05-09T21:47:08.895253Z","end":"2026-05-09T21:47:09.094702Z","steps":["trace[2071990136] 'agreement among raft nodes before linearized reading' (duration: 199.233614ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:47:09.094875Z","caller":"traceutil/trace.go:171","msg":"trace[835873675] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"366.043013ms","start":"2026-05-09T21:47:08.728762Z","end":"2026-05-09T21:47:09.094805Z","steps":["trace[835873675] 'process raft request' (duration: 365.424962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:47:09.095852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:47:08.728732Z","time spent":"366.36654ms","remote":"127.0.0.1:57904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:47:25.47677Z","caller":"traceutil/trace.go:171","msg":"trace[945832675] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"247.739061ms","start":"2026-05-09T21:47:25.229Z","end":"2026-05-09T21:47:25.476739Z","steps":["trace[945832675] 'process raft request' (duration: 196.079562ms)","trace[945832675] 'compare' (duration: 51.521685ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:41.26477Z","caller":"traceutil/trace.go:171","msg":"trace[1590788642] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"280.772752ms","start":"2026-05-09T21:48:40.983965Z","end":"2026-05-09T21:48:41.264738Z","steps":["trace[1590788642] 'process raft request' (duration: 259.231726ms)","trace[1590788642] 'compare' (duration: 21.269381ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:48:41.301408Z","caller":"traceutil/trace.go:171","msg":"trace[1788661105] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"207.941612ms","start":"2026-05-09T21:48:41.093435Z","end":"2026-05-09T21:48:41.301377Z","steps":["trace[1788661105] 'process raft request' (duration: 207.798829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:48:41.71163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.82213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:48:41.711764Z","caller":"traceutil/trace.go:171","msg":"trace[1264138868] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"305.195945ms","start":"2026-05-09T21:48:41.406546Z","end":"2026-05-09T21:48:41.711742Z","steps":["trace[1264138868] 'process raft request' (duration: 186.937977ms)","trace[1264138868] 'compare' (duration: 117.622416ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:48:41.711829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:48:41.40652Z","time spent":"305.281727ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T21:48:41.712257Z","caller":"traceutil/trace.go:171","msg":"trace[1308045935] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"130.754845ms","start":"2026-05-09T21:48:41.58146Z","end":"2026-05-09T21:48:41.712215Z","steps":["trace[1308045935] 'process raft request' (duration: 130.608312ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:49:25.969193Z","caller":"traceutil/trace.go:171","msg":"trace[560757875] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"102.210967ms","start":"2026-05-09T21:49:25.866949Z","end":"2026-05-09T21:49:25.96916Z","steps":["trace[560757875] 'process raft request' (duration: 102.008963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:49:32.604541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.085508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T21:49:32.604731Z","caller":"traceutil/trace.go:171","msg":"trace[1724720033] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"245.261551ms","start":"2026-05-09T21:49:32.359368Z","end":"2026-05-09T21:49:32.60463Z","steps":["trace[1724720033] 'range keys from in-memory index tree' (duration: 244.807332ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:50:58.70067Z","caller":"traceutil/trace.go:171","msg":"trace[86959515] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"197.084092ms","start":"2026-05-09T21:50:58.503548Z","end":"2026-05-09T21:50:58.700632Z","steps":["trace[86959515] 'process raft request' (duration: 179.575941ms)","trace[86959515] 'compare' (duration: 17.319727ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:50:59.092425Z","caller":"traceutil/trace.go:171","msg":"trace[1213321385] transaction","detail":"{read_only:false; response_revision:2801; number_of_response:1; }","duration":"126.840153ms","start":"2026-05-09T21:50:58.965554Z","end":"2026-05-09T21:50:59.092394Z","steps":["trace[1213321385] 'process raft request' (duration: 126.622388ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:04.683827Z","caller":"traceutil/trace.go:171","msg":"trace[2000477153] transaction","detail":"{read_only:false; response_revision:4853; number_of_response:1; }","duration":"149.074601ms","start":"2026-05-09T21:55:04.534717Z","end":"2026-05-09T21:55:04.683792Z","steps":["trace[2000477153] 'process raft request' (duration: 148.735783ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:04.683797Z","caller":"traceutil/trace.go:171","msg":"trace[1375579522] linearizableReadLoop","detail":"{readStateIndex:5039; appliedIndex:5037; }","duration":"102.141782ms","start":"2026-05-09T21:55:04.581609Z","end":"2026-05-09T21:55:04.683751Z","steps":["trace[1375579522] 'read index received' (duration: 101.836455ms)","trace[1375579522] 'applied index is now lower than readState.Index' (duration: 303.367µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:55:04.684055Z","caller":"traceutil/trace.go:171","msg":"trace[1588077419] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"144.592985ms","start":"2026-05-09T21:55:04.539418Z","end":"2026-05-09T21:55:04.684011Z","steps":["trace[1588077419] 'process raft request' (duration: 144.250837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:04.684132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.47329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1340db4b-48f3-4116-b23c-b7d233abc973\" ","response":"range_response_count:1 size:3893"} {"level":"info","ts":"2026-05-09T21:55:04.685397Z","caller":"traceutil/trace.go:171","msg":"trace[620785395] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1340db4b-48f3-4116-b23c-b7d233abc973; range_end:; response_count:1; response_revision:4854; }","duration":"103.820723ms","start":"2026-05-09T21:55:04.581544Z","end":"2026-05-09T21:55:04.685365Z","steps":["trace[620785395] 'agreement among raft nodes before linearized reading' (duration: 102.382478ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:10.140393Z","caller":"traceutil/trace.go:171","msg":"trace[1066237920] linearizableReadLoop","detail":"{readStateIndex:5248; appliedIndex:5247; }","duration":"214.443726ms","start":"2026-05-09T21:55:09.925928Z","end":"2026-05-09T21:55:10.140372Z","steps":["trace[1066237920] 'read index received' (duration: 214.22449ms)","trace[1066237920] 'applied index is now lower than readState.Index' (duration: 218.246µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:55:10.140527Z","caller":"traceutil/trace.go:171","msg":"trace[1630381050] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"226.596383ms","start":"2026-05-09T21:55:09.91392Z","end":"2026-05-09T21:55:10.140516Z","steps":["trace[1630381050] 'process raft request' (duration: 226.284346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:10.140765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.815844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T21:55:10.140799Z","caller":"traceutil/trace.go:171","msg":"trace[595849783] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:5056; }","duration":"214.894266ms","start":"2026-05-09T21:55:09.925894Z","end":"2026-05-09T21:55:10.140788Z","steps":["trace[595849783] 'agreement among raft nodes before linearized reading' (duration: 214.743903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:22.059369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.681985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T21:55:22.059541Z","caller":"traceutil/trace.go:171","msg":"trace[1404181322] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"307.973164ms","start":"2026-05-09T21:55:21.751531Z","end":"2026-05-09T21:55:22.059504Z","steps":["trace[1404181322] 'process raft request' (duration: 150.108825ms)","trace[1404181322] 'compare' (duration: 157.336457ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:55:22.05961Z","caller":"traceutil/trace.go:171","msg":"trace[156564887] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"193.03952ms","start":"2026-05-09T21:55:21.866563Z","end":"2026-05-09T21:55:22.059602Z","steps":["trace[156564887] 'process raft request' (duration: 192.983378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:22.059641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:55:21.751503Z","time spent":"308.106718ms","remote":"127.0.0.1:57556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T21:55:22.059758Z","caller":"traceutil/trace.go:171","msg":"trace[1678262888] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"299.237719ms","start":"2026-05-09T21:55:21.760511Z","end":"2026-05-09T21:55:22.059749Z","steps":["trace[1678262888] 'process raft request' (duration: 298.974943ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:22.059938Z","caller":"traceutil/trace.go:171","msg":"trace[1259642759] linearizableReadLoop","detail":"{readStateIndex:5489; appliedIndex:5488; }","duration":"306.07045ms","start":"2026-05-09T21:55:21.753851Z","end":"2026-05-09T21:55:22.059922Z","steps":["trace[1259642759] 'read index received' (duration: 147.806291ms)","trace[1259642759] 'applied index is now lower than readState.Index' (duration: 158.263049ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:55:22.060256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.455445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1475"} {"level":"info","ts":"2026-05-09T21:55:22.060284Z","caller":"traceutil/trace.go:171","msg":"trace[1839715894] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5291; }","duration":"305.505586ms","start":"2026-05-09T21:55:21.75477Z","end":"2026-05-09T21:55:22.060275Z","steps":["trace[1839715894] 'agreement among raft nodes before linearized reading' (duration: 305.407024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:22.0603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:55:21.754759Z","time spent":"305.537837ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":1498,"request content":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-05-09T21:55:22.060476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.130368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-09T21:55:22.060615Z","caller":"traceutil/trace.go:171","msg":"trace[995687241] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5291; }","duration":"225.341352ms","start":"2026-05-09T21:55:21.835244Z","end":"2026-05-09T21:55:22.060585Z","steps":["trace[995687241] 'agreement among raft nodes before linearized reading' (duration: 224.929503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:22.063142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.975049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:1267"} {"level":"info","ts":"2026-05-09T21:55:22.063731Z","caller":"traceutil/trace.go:171","msg":"trace[1225327298] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:5291; }","duration":"309.653724ms","start":"2026-05-09T21:55:21.753912Z","end":"2026-05-09T21:55:22.063566Z","steps":["trace[1225327298] 'agreement among raft nodes before linearized reading' (duration: 306.249834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:22.070112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:55:21.753907Z","time spent":"316.149198ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1290,"request content":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-grafana\" "} {"level":"warn","ts":"2026-05-09T21:55:22.07138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.49832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1459"} {"level":"info","ts":"2026-05-09T21:55:22.07148Z","caller":"traceutil/trace.go:171","msg":"trace[1607258856] range","detail":"{range_begin:/registry/ingress/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5291; }","duration":"317.659034ms","start":"2026-05-09T21:55:21.753803Z","end":"2026-05-09T21:55:22.071462Z","steps":["trace[1607258856] 'agreement among raft nodes before linearized reading' (duration: 317.398458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:22.071522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T21:55:21.753781Z","time spent":"317.731705ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1482,"request content":"key:\"/registry/ingress/monitoring/kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-05-09T21:55:30.643761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.882638ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T21:55:30.643904Z","caller":"traceutil/trace.go:171","msg":"trace[102101689] linearizableReadLoop","detail":"{readStateIndex:5712; appliedIndex:5711; }","duration":"203.943508ms","start":"2026-05-09T21:55:30.439937Z","end":"2026-05-09T21:55:30.64388Z","steps":["trace[102101689] 'read index received' (duration: 92.939796ms)","trace[102101689] 'applied index is now lower than readState.Index' (duration: 111.001872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T21:55:30.644436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.47601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-09T21:55:30.644554Z","caller":"traceutil/trace.go:171","msg":"trace[684747855] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5507; }","duration":"204.648444ms","start":"2026-05-09T21:55:30.439877Z","end":"2026-05-09T21:55:30.644525Z","steps":["trace[684747855] 'agreement among raft nodes before linearized reading' (duration: 204.296505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:30.657819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.753974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8fba7fc9-6acd-4d16-a062-9e204489e571\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-09T21:55:30.657895Z","caller":"traceutil/trace.go:171","msg":"trace[51848081] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8fba7fc9-6acd-4d16-a062-9e204489e571; range_end:; response_count:1; response_revision:5508; }","duration":"195.903287ms","start":"2026-05-09T21:55:30.461972Z","end":"2026-05-09T21:55:30.657876Z","steps":["trace[51848081] 'agreement among raft nodes before linearized reading' (duration: 195.658762ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:31.01945Z","caller":"traceutil/trace.go:171","msg":"trace[1063424254] linearizableReadLoop","detail":"{readStateIndex:5715; appliedIndex:5714; }","duration":"192.714547ms","start":"2026-05-09T21:55:30.82671Z","end":"2026-05-09T21:55:31.019425Z","steps":["trace[1063424254] 'read index received' (duration: 192.561694ms)","trace[1063424254] 'applied index is now lower than readState.Index' (duration: 151.733µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:55:31.019533Z","caller":"traceutil/trace.go:171","msg":"trace[1007177328] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"193.481294ms","start":"2026-05-09T21:55:30.826006Z","end":"2026-05-09T21:55:31.019488Z","steps":["trace[1007177328] 'process raft request' (duration: 193.253589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:31.019863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.132805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111444"} {"level":"info","ts":"2026-05-09T21:55:31.019927Z","caller":"traceutil/trace.go:171","msg":"trace[2088682106] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:5510; }","duration":"193.228656ms","start":"2026-05-09T21:55:30.826686Z","end":"2026-05-09T21:55:31.019915Z","steps":["trace[2088682106] 'agreement among raft nodes before linearized reading' (duration: 192.857299ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:31.148745Z","caller":"traceutil/trace.go:171","msg":"trace[698040967] linearizableReadLoop","detail":"{readStateIndex:5717; appliedIndex:5715; }","duration":"129.193738ms","start":"2026-05-09T21:55:31.019524Z","end":"2026-05-09T21:55:31.148718Z","steps":["trace[698040967] 'read index received' (duration: 107.325111ms)","trace[698040967] 'applied index is now lower than readState.Index' (duration: 21.867417ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:55:31.148845Z","caller":"traceutil/trace.go:171","msg":"trace[192491348] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"221.166061ms","start":"2026-05-09T21:55:30.927626Z","end":"2026-05-09T21:55:31.148792Z","steps":["trace[192491348] 'process raft request' (duration: 221.029748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:31.14902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.346665ms","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-05-09T21:55:31.149087Z","caller":"traceutil/trace.go:171","msg":"trace[1942056618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5512; }","duration":"286.451507ms","start":"2026-05-09T21:55:30.862623Z","end":"2026-05-09T21:55:31.149075Z","steps":["trace[1942056618] 'agreement among raft nodes before linearized reading' (duration: 286.210552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T21:55:31.149149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.889982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-sqnx2\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-05-09T21:55:31.149272Z","caller":"traceutil/trace.go:171","msg":"trace[2038447871] range","detail":"{range_begin:/registry/pods/openstack/keepalived-sqnx2; range_end:; response_count:1; response_revision:5512; }","duration":"145.068855ms","start":"2026-05-09T21:55:31.004175Z","end":"2026-05-09T21:55:31.149244Z","steps":["trace[2038447871] 'agreement among raft nodes before linearized reading' (duration: 144.636916ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:31.148743Z","caller":"traceutil/trace.go:171","msg":"trace[13684229] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"248.422225ms","start":"2026-05-09T21:55:30.900289Z","end":"2026-05-09T21:55:31.148711Z","steps":["trace[13684229] 'process raft request' (duration: 226.542748ms)","trace[13684229] 'compare' (duration: 21.679533ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T21:55:31.152575Z","caller":"traceutil/trace.go:171","msg":"trace[617520934] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"118.491434ms","start":"2026-05-09T21:55:31.034056Z","end":"2026-05-09T21:55:31.152547Z","steps":["trace[617520934] 'process raft request' (duration: 118.327621ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:55:31.152742Z","caller":"traceutil/trace.go:171","msg":"trace[537478817] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"126.387542ms","start":"2026-05-09T21:55:31.026322Z","end":"2026-05-09T21:55:31.15271Z","steps":["trace[537478817] 'process raft request' (duration: 125.900462ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T21:56:31.840021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-05-09T21:56:31.91402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"71.872778ms","hash":557451715,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":33468416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T21:56:31.914113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":557451715,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-05-09T22:01:31.847474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6053} {"level":"info","ts":"2026-05-09T22:01:31.939362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6053,"took":"89.831655ms","hash":3911703700,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":21790720,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-09T22:01:31.939433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3911703700,"revision":6053,"compact-revision":2983} {"level":"info","ts":"2026-05-09T22:01:52.481193Z","caller":"traceutil/trace.go:171","msg":"trace[1536541081] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"142.264739ms","start":"2026-05-09T22:01:52.3389Z","end":"2026-05-09T22:01:52.481165Z","steps":["trace[1536541081] 'process raft request' (duration: 142.104926ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:01:52.698268Z","caller":"traceutil/trace.go:171","msg":"trace[1354321054] linearizableReadLoop","detail":"{readStateIndex:8682; appliedIndex:8681; }","duration":"355.616323ms","start":"2026-05-09T22:01:52.342625Z","end":"2026-05-09T22:01:52.698241Z","steps":["trace[1354321054] 'read index received' (duration: 138.530813ms)","trace[1354321054] 'applied index is now lower than readState.Index' (duration: 217.08425ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:01:52.698402Z","caller":"traceutil/trace.go:171","msg":"trace[1512590348] transaction","detail":"{read_only:false; response_revision:8384; number_of_response:1; }","duration":"358.603924ms","start":"2026-05-09T22:01:52.339785Z","end":"2026-05-09T22:01:52.698389Z","steps":["trace[1512590348] 'process raft request' (duration: 344.359014ms)","trace[1512590348] 'compare' (duration: 13.984734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:01:52.698509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:01:52.339777Z","time spent":"358.652074ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:01:52.698542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.878608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:01:52.69867Z","caller":"traceutil/trace.go:171","msg":"trace[1879210220] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8384; }","duration":"356.222475ms","start":"2026-05-09T22:01:52.342425Z","end":"2026-05-09T22:01:52.698647Z","steps":["trace[1879210220] 'agreement among raft nodes before linearized reading' (duration: 356.018481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:01:52.698729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:01:52.342417Z","time spent":"356.298746ms","remote":"127.0.0.1:42560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"info","ts":"2026-05-09T22:01:52.72052Z","caller":"traceutil/trace.go:171","msg":"trace[430426747] transaction","detail":"{read_only:false; response_revision:8385; number_of_response:1; }","duration":"138.993294ms","start":"2026-05-09T22:01:52.581506Z","end":"2026-05-09T22:01:52.7205Z","steps":["trace[430426747] 'process raft request' (duration: 138.889451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:01:52.732138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.191479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:01:52.732225Z","caller":"traceutil/trace.go:171","msg":"trace[317500440] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:8388; }","duration":"111.334682ms","start":"2026-05-09T22:01:52.620872Z","end":"2026-05-09T22:01:52.732206Z","steps":["trace[317500440] 'agreement among raft nodes before linearized reading' (duration: 111.179449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:01:52.732435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.024245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:01:52.732472Z","caller":"traceutil/trace.go:171","msg":"trace[1255796209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8388; }","duration":"110.098277ms","start":"2026-05-09T22:01:52.622364Z","end":"2026-05-09T22:01:52.732462Z","steps":["trace[1255796209] 'agreement among raft nodes before linearized reading' (duration: 110.043306ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:02:20.875807Z","caller":"traceutil/trace.go:171","msg":"trace[440630822] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"249.071139ms","start":"2026-05-09T22:02:20.626651Z","end":"2026-05-09T22:02:20.875722Z","steps":["trace[440630822] 'process raft request' (duration: 248.861305ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:02:21.064241Z","caller":"traceutil/trace.go:171","msg":"trace[842384878] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"173.746249ms","start":"2026-05-09T22:02:20.89046Z","end":"2026-05-09T22:02:21.064206Z","steps":["trace[842384878] 'process raft request' (duration: 172.894742ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:02:24.344436Z","caller":"traceutil/trace.go:171","msg":"trace[165153885] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"195.603993ms","start":"2026-05-09T22:02:24.148799Z","end":"2026-05-09T22:02:24.344403Z","steps":["trace[165153885] 'process raft request' (duration: 195.332468ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:02:33.681863Z","caller":"traceutil/trace.go:171","msg":"trace[186487562] transaction","detail":"{read_only:false; response_revision:8701; number_of_response:1; }","duration":"129.848757ms","start":"2026-05-09T22:02:33.551977Z","end":"2026-05-09T22:02:33.681825Z","steps":["trace[186487562] 'process raft request' (duration: 129.570731ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:02:33.772151Z","caller":"traceutil/trace.go:171","msg":"trace[190436346] transaction","detail":"{read_only:false; response_revision:8702; number_of_response:1; }","duration":"146.586068ms","start":"2026-05-09T22:02:33.625512Z","end":"2026-05-09T22:02:33.772098Z","steps":["trace[190436346] 'process raft request' (duration: 146.359073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:02:33.772226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.092108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9tbzv\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-05-09T22:02:33.77231Z","caller":"traceutil/trace.go:171","msg":"trace[302175687] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9tbzv; range_end:; response_count:1; response_revision:8702; }","duration":"148.203831ms","start":"2026-05-09T22:02:33.624086Z","end":"2026-05-09T22:02:33.77229Z","steps":["trace[302175687] 'agreement among raft nodes before linearized reading' (duration: 147.976526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:02:34.660359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.31756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2213912"} {"level":"info","ts":"2026-05-09T22:02:34.660425Z","caller":"traceutil/trace.go:171","msg":"trace[1936530580] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8710; }","duration":"173.438553ms","start":"2026-05-09T22:02:34.486971Z","end":"2026-05-09T22:02:34.66041Z","steps":["trace[1936530580] 'range keys from in-memory index tree' (duration: 172.571586ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:02:51.337141Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e6.wal"} {"level":"warn","ts":"2026-05-09T22:03:51.714957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.432335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T22:03:51.715187Z","caller":"traceutil/trace.go:171","msg":"trace[1733671867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9462; }","duration":"139.700471ms","start":"2026-05-09T22:03:51.575441Z","end":"2026-05-09T22:03:51.715141Z","steps":["trace[1733671867] 'range keys from in-memory index tree' (duration: 139.177219ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:04:18.272664Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:04:18.281813Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T22:04:18.28193Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T22:06:31.853316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8142} {"level":"info","ts":"2026-05-09T22:06:31.910127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8142,"took":"55.300306ms","hash":2513732845,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":29593600,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T22:06:31.910192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2513732845,"revision":8142,"compact-revision":6053} {"level":"info","ts":"2026-05-09T22:07:35.450169Z","caller":"traceutil/trace.go:171","msg":"trace[1907291848] transaction","detail":"{read_only:false; response_revision:11315; number_of_response:1; }","duration":"149.661691ms","start":"2026-05-09T22:07:35.300474Z","end":"2026-05-09T22:07:35.450135Z","steps":["trace[1907291848] 'process raft request' (duration: 149.480248ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:11:31.860211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10753} {"level":"info","ts":"2026-05-09T22:11:31.94422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10753,"took":"81.492626ms","hash":4032580134,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":27901952,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T22:11:31.944346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4032580134,"revision":10753,"compact-revision":8142} {"level":"info","ts":"2026-05-09T22:13:07.449715Z","caller":"traceutil/trace.go:171","msg":"trace[230874841] transaction","detail":"{read_only:false; response_revision:13573; number_of_response:1; }","duration":"112.785689ms","start":"2026-05-09T22:13:07.336899Z","end":"2026-05-09T22:13:07.449685Z","steps":["trace[230874841] 'process raft request' (duration: 90.633264ms)","trace[230874841] 'compare' (duration: 21.957231ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:13:07.449909Z","caller":"traceutil/trace.go:171","msg":"trace[919267395] transaction","detail":"{read_only:false; response_revision:13574; number_of_response:1; }","duration":"107.332368ms","start":"2026-05-09T22:13:07.342537Z","end":"2026-05-09T22:13:07.44987Z","steps":["trace[919267395] 'process raft request' (duration: 107.097563ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:13:24.435744Z","caller":"traceutil/trace.go:171","msg":"trace[2033440015] transaction","detail":"{read_only:false; response_revision:13702; number_of_response:1; }","duration":"136.022794ms","start":"2026-05-09T22:13:24.299694Z","end":"2026-05-09T22:13:24.435716Z","steps":["trace[2033440015] 'process raft request' (duration: 135.873411ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:14:24.152915Z","caller":"traceutil/trace.go:171","msg":"trace[129389453] linearizableReadLoop","detail":"{readStateIndex:14715; appliedIndex:14714; }","duration":"313.016103ms","start":"2026-05-09T22:14:23.839873Z","end":"2026-05-09T22:14:24.152889Z","steps":["trace[129389453] 'read index received' (duration: 285.4999ms)","trace[129389453] 'applied index is now lower than readState.Index' (duration: 27.515153ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:14:24.15295Z","caller":"traceutil/trace.go:171","msg":"trace[843585653] transaction","detail":"{read_only:false; response_revision:14227; number_of_response:1; }","duration":"352.18256ms","start":"2026-05-09T22:14:23.80072Z","end":"2026-05-09T22:14:24.152902Z","steps":["trace[843585653] 'process raft request' (duration: 324.715309ms)","trace[843585653] 'compare' (duration: 27.066573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:14:24.153165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.948516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T22:14:24.153241Z","caller":"traceutil/trace.go:171","msg":"trace[1422197288] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:14227; }","duration":"160.075609ms","start":"2026-05-09T22:14:23.993144Z","end":"2026-05-09T22:14:24.15322Z","steps":["trace[1422197288] 'agreement among raft nodes before linearized reading' (duration: 159.952496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:14:24.153269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.603191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-09T22:14:24.153314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.377561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3adfd5d3-ff64-4bce-8952-8ea652b8ac8c\" ","response":"range_response_count:1 size:3753"} {"level":"info","ts":"2026-05-09T22:14:24.153358Z","caller":"traceutil/trace.go:171","msg":"trace[1582434071] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:14227; }","duration":"166.752843ms","start":"2026-05-09T22:14:23.986584Z","end":"2026-05-09T22:14:24.153337Z","steps":["trace[1582434071] 'agreement among raft nodes before linearized reading' (duration: 166.493797ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:14:24.153416Z","caller":"traceutil/trace.go:171","msg":"trace[1334153307] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3adfd5d3-ff64-4bce-8952-8ea652b8ac8c; range_end:; response_count:1; response_revision:14227; }","duration":"313.522394ms","start":"2026-05-09T22:14:23.839833Z","end":"2026-05-09T22:14:24.153355Z","steps":["trace[1334153307] 'agreement among raft nodes before linearized reading' (duration: 313.212977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:14:24.15335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:14:23.80069Z","time spent":"352.351644ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:14:24.153509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:14:23.839813Z","time spent":"313.639616ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3776,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3adfd5d3-ff64-4bce-8952-8ea652b8ac8c\" "} {"level":"warn","ts":"2026-05-09T22:15:50.554198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.287868ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T22:16:31.873021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12848} {"level":"info","ts":"2026-05-09T22:16:31.953116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12848,"took":"76.401012ms","hash":2709756175,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":30261248,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T22:16:31.953215Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2709756175,"revision":12848,"compact-revision":10753} {"level":"info","ts":"2026-05-09T22:21:26.426355Z","caller":"traceutil/trace.go:171","msg":"trace[795103247] transaction","detail":"{read_only:false; response_revision:17559; number_of_response:1; }","duration":"129.102296ms","start":"2026-05-09T22:21:26.29722Z","end":"2026-05-09T22:21:26.426323Z","steps":["trace[795103247] 'process raft request' (duration: 128.878101ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:21:27.195581Z","caller":"traceutil/trace.go:171","msg":"trace[649052207] transaction","detail":"{read_only:false; response_revision:17561; number_of_response:1; }","duration":"295.911805ms","start":"2026-05-09T22:21:26.899645Z","end":"2026-05-09T22:21:27.195557Z","steps":["trace[649052207] 'process raft request' (duration: 295.776482ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:21:27.196403Z","caller":"traceutil/trace.go:171","msg":"trace[14062081] linearizableReadLoop","detail":"{readStateIndex:18154; appliedIndex:18154; }","duration":"128.226969ms","start":"2026-05-09T22:21:27.068161Z","end":"2026-05-09T22:21:27.196388Z","steps":["trace[14062081] 'read index received' (duration: 128.222389ms)","trace[14062081] 'applied index is now lower than readState.Index' (duration: 3.52µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:21:27.196545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.376671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T22:21:27.19795Z","caller":"traceutil/trace.go:171","msg":"trace[1945298644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17561; }","duration":"129.81848ms","start":"2026-05-09T22:21:27.068104Z","end":"2026-05-09T22:21:27.197922Z","steps":["trace[1945298644] 'agreement among raft nodes before linearized reading' (duration: 128.340681ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:21:27.364256Z","caller":"traceutil/trace.go:171","msg":"trace[1126200045] transaction","detail":"{read_only:false; response_revision:17562; number_of_response:1; }","duration":"176.679006ms","start":"2026-05-09T22:21:27.187534Z","end":"2026-05-09T22:21:27.364213Z","steps":["trace[1126200045] 'process raft request' (duration: 150.314619ms)","trace[1126200045] 'compare' (duration: 26.200653ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:21:27.40611Z","caller":"traceutil/trace.go:171","msg":"trace[947883707] transaction","detail":"{read_only:false; response_revision:17563; number_of_response:1; }","duration":"201.715325ms","start":"2026-05-09T22:21:27.204361Z","end":"2026-05-09T22:21:27.406076Z","steps":["trace[947883707] 'process raft request' (duration: 201.499591ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:21:27.406277Z","caller":"traceutil/trace.go:171","msg":"trace[469382683] linearizableReadLoop","detail":"{readStateIndex:18156; appliedIndex:18154; }","duration":"176.306518ms","start":"2026-05-09T22:21:27.229944Z","end":"2026-05-09T22:21:27.406251Z","steps":["trace[469382683] 'read index received' (duration: 107.923113ms)","trace[469382683] 'applied index is now lower than readState.Index' (duration: 68.381185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:21:27.406599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.654405ms","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-05-09T22:21:27.406666Z","caller":"traceutil/trace.go:171","msg":"trace[736157618] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17564; }","duration":"176.788527ms","start":"2026-05-09T22:21:27.229865Z","end":"2026-05-09T22:21:27.406653Z","steps":["trace[736157618] 'agreement among raft nodes before linearized reading' (duration: 176.577783ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:21:27.406834Z","caller":"traceutil/trace.go:171","msg":"trace[552103906] transaction","detail":"{read_only:false; response_revision:17564; number_of_response:1; }","duration":"106.913653ms","start":"2026-05-09T22:21:27.299509Z","end":"2026-05-09T22:21:27.406423Z","steps":["trace[552103906] 'process raft request' (duration: 106.508685ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:21:31.886173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15313} {"level":"info","ts":"2026-05-09T22:21:31.977716Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15313,"took":"88.364474ms","hash":2996197704,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":32055296,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T22:21:31.977819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996197704,"revision":15313,"compact-revision":12848} {"level":"info","ts":"2026-05-09T22:25:02.926313Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:25:02.932808Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T22:25:02.933022Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T22:26:31.909294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17659} {"level":"info","ts":"2026-05-09T22:26:32.016264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17659,"took":"103.262272ms","hash":2541437425,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":33812480,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T22:26:32.016372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2541437425,"revision":17659,"compact-revision":15313} {"level":"warn","ts":"2026-05-09T22:27:14.961261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.957554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:27:14.961495Z","caller":"traceutil/trace.go:171","msg":"trace[322443363] transaction","detail":"{read_only:false; response_revision:20252; number_of_response:1; }","duration":"196.225548ms","start":"2026-05-09T22:27:14.765197Z","end":"2026-05-09T22:27:14.961423Z","steps":["trace[322443363] 'process raft request' (duration: 58.989838ms)","trace[322443363] 'compare' (duration: 136.71322ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:27:45.872407Z","caller":"traceutil/trace.go:171","msg":"trace[2124159405] transaction","detail":"{read_only:false; response_revision:20412; number_of_response:1; }","duration":"124.921294ms","start":"2026-05-09T22:27:45.747455Z","end":"2026-05-09T22:27:45.872377Z","steps":["trace[2124159405] 'process raft request' (duration: 124.73591ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:45.872643Z","caller":"traceutil/trace.go:171","msg":"trace[2001695052] transaction","detail":"{read_only:false; response_revision:20413; number_of_response:1; }","duration":"125.082267ms","start":"2026-05-09T22:27:45.74753Z","end":"2026-05-09T22:27:45.872612Z","steps":["trace[2001695052] 'process raft request' (duration: 124.798282ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:51.207749Z","caller":"traceutil/trace.go:171","msg":"trace[1442358714] transaction","detail":"{read_only:false; response_revision:20441; number_of_response:1; }","duration":"111.502636ms","start":"2026-05-09T22:27:51.096214Z","end":"2026-05-09T22:27:51.207717Z","steps":["trace[1442358714] 'process raft request' (duration: 111.321582ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:57.025069Z","caller":"traceutil/trace.go:171","msg":"trace[601982713] linearizableReadLoop","detail":"{readStateIndex:21161; appliedIndex:21160; }","duration":"125.098517ms","start":"2026-05-09T22:27:56.899936Z","end":"2026-05-09T22:27:57.025035Z","steps":["trace[601982713] 'read index received' (duration: 124.910463ms)","trace[601982713] 'applied index is now lower than readState.Index' (duration: 187.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:27:57.025239Z","caller":"traceutil/trace.go:171","msg":"trace[2100385647] transaction","detail":"{read_only:false; response_revision:20471; number_of_response:1; }","duration":"193.715477ms","start":"2026-05-09T22:27:56.831441Z","end":"2026-05-09T22:27:57.025156Z","steps":["trace[2100385647] 'process raft request' (duration: 193.435562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:57.025343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.398783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-05-09T22:27:57.025426Z","caller":"traceutil/trace.go:171","msg":"trace[505638042] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:20471; }","duration":"125.539746ms","start":"2026-05-09T22:27:56.899864Z","end":"2026-05-09T22:27:57.025404Z","steps":["trace[505638042] 'agreement among raft nodes before linearized reading' (duration: 125.29688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:27:57.452794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.782912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-09T22:27:57.45288Z","caller":"traceutil/trace.go:171","msg":"trace[1692759691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20473; }","duration":"110.913384ms","start":"2026-05-09T22:27:57.341945Z","end":"2026-05-09T22:27:57.452859Z","steps":["trace[1692759691] 'range keys from in-memory index tree' (duration: 110.617499ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:27:57.615564Z","caller":"traceutil/trace.go:171","msg":"trace[569833948] transaction","detail":"{read_only:false; response_revision:20474; number_of_response:1; }","duration":"155.774269ms","start":"2026-05-09T22:27:57.459759Z","end":"2026-05-09T22:27:57.615533Z","steps":["trace[569833948] 'process raft request' (duration: 155.613246ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:31.916852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20036} {"level":"info","ts":"2026-05-09T22:31:32.028606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20036,"took":"108.992416ms","hash":2399573992,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":31121408,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T22:31:32.028659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2399573992,"revision":20036,"compact-revision":17659} {"level":"info","ts":"2026-05-09T22:31:45.020029Z","caller":"traceutil/trace.go:171","msg":"trace[1859607068] linearizableReadLoop","detail":"{readStateIndex:23633; appliedIndex:23632; }","duration":"134.836832ms","start":"2026-05-09T22:31:44.88515Z","end":"2026-05-09T22:31:45.019987Z","steps":["trace[1859607068] 'read index received' (duration: 69.531428ms)","trace[1859607068] 'applied index is now lower than readState.Index' (duration: 65.301914ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:45.020061Z","caller":"traceutil/trace.go:171","msg":"trace[671476186] transaction","detail":"{read_only:false; response_revision:22869; number_of_response:1; }","duration":"221.011522ms","start":"2026-05-09T22:31:44.799007Z","end":"2026-05-09T22:31:45.020019Z","steps":["trace[671476186] 'process raft request' (duration: 155.552636ms)","trace[671476186] 'compare' (duration: 64.803274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:31:45.020271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.112519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-09T22:31:45.020354Z","caller":"traceutil/trace.go:171","msg":"trace[1119381988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22870; }","duration":"135.259102ms","start":"2026-05-09T22:31:44.885077Z","end":"2026-05-09T22:31:45.020336Z","steps":["trace[1119381988] 'agreement among raft nodes before linearized reading' (duration: 135.036387ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:31:45.176255Z","caller":"traceutil/trace.go:171","msg":"trace[666387697] linearizableReadLoop","detail":"{readStateIndex:23636; appliedIndex:23635; }","duration":"102.608178ms","start":"2026-05-09T22:31:45.073626Z","end":"2026-05-09T22:31:45.176234Z","steps":["trace[666387697] 'read index received' (duration: 102.435984ms)","trace[666387697] 'applied index is now lower than readState.Index' (duration: 171.384µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:31:45.176359Z","caller":"traceutil/trace.go:171","msg":"trace[1205437383] transaction","detail":"{read_only:false; response_revision:22872; number_of_response:1; }","duration":"150.928884ms","start":"2026-05-09T22:31:45.025422Z","end":"2026-05-09T22:31:45.176351Z","steps":["trace[1205437383] 'process raft request' (duration: 150.692599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:45.176526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.778272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T22:31:45.176569Z","caller":"traceutil/trace.go:171","msg":"trace[1751013643] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:22872; }","duration":"102.871553ms","start":"2026-05-09T22:31:45.073687Z","end":"2026-05-09T22:31:45.176559Z","steps":["trace[1751013643] 'agreement among raft nodes before linearized reading' (duration: 102.73392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:31:45.176934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.303643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:31:45.176966Z","caller":"traceutil/trace.go:171","msg":"trace[824958334] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:22872; }","duration":"103.395605ms","start":"2026-05-09T22:31:45.073562Z","end":"2026-05-09T22:31:45.176958Z","steps":["trace[824958334] 'agreement among raft nodes before linearized reading' (duration: 103.339113ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.016798Z","caller":"traceutil/trace.go:171","msg":"trace[1441650027] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"195.420381ms","start":"2026-05-09T22:35:19.821348Z","end":"2026-05-09T22:35:20.016768Z","steps":["trace[1441650027] 'process raft request' (duration: 195.263128ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.184086Z","caller":"traceutil/trace.go:171","msg":"trace[628794536] linearizableReadLoop","detail":"{readStateIndex:25874; appliedIndex:25872; }","duration":"176.794686ms","start":"2026-05-09T22:35:20.00726Z","end":"2026-05-09T22:35:20.184055Z","steps":["trace[628794536] 'read index received' (duration: 9.320767ms)","trace[628794536] 'applied index is now lower than readState.Index' (duration: 167.472419ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:20.184386Z","caller":"traceutil/trace.go:171","msg":"trace[919092218] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"361.392021ms","start":"2026-05-09T22:35:19.822979Z","end":"2026-05-09T22:35:20.184371Z","steps":["trace[919092218] 'process raft request' (duration: 295.411057ms)","trace[919092218] 'compare' (duration: 65.438893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:20.184498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:19.822969Z","time spent":"361.454452ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:20.184609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.340149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:20.184942Z","caller":"traceutil/trace.go:171","msg":"trace[1737729939] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25054; }","duration":"177.728816ms","start":"2026-05-09T22:35:20.00719Z","end":"2026-05-09T22:35:20.184919Z","steps":["trace[1737729939] 'agreement among raft nodes before linearized reading' (duration: 177.315488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:20.185488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.144771ms","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-05-09T22:35:20.1855Z","caller":"traceutil/trace.go:171","msg":"trace[397072850] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"112.968996ms","start":"2026-05-09T22:35:20.072505Z","end":"2026-05-09T22:35:20.185474Z","steps":["trace[397072850] 'process raft request' (duration: 112.732842ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.185531Z","caller":"traceutil/trace.go:171","msg":"trace[544598888] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25055; }","duration":"134.217573ms","start":"2026-05-09T22:35:20.051303Z","end":"2026-05-09T22:35:20.18552Z","steps":["trace[544598888] 'agreement among raft nodes before linearized reading' (duration: 134.06246ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.466756Z","caller":"traceutil/trace.go:171","msg":"trace[1787109445] linearizableReadLoop","detail":"{readStateIndex:25876; appliedIndex:25875; }","duration":"252.920724ms","start":"2026-05-09T22:35:20.213184Z","end":"2026-05-09T22:35:20.466105Z","steps":["trace[1787109445] 'read index received' (duration: 252.828132ms)","trace[1787109445] 'applied index is now lower than readState.Index' (duration: 91.372µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:20.467193Z","caller":"traceutil/trace.go:171","msg":"trace[130869177] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"268.588778ms","start":"2026-05-09T22:35:20.197585Z","end":"2026-05-09T22:35:20.466174Z","steps":["trace[130869177] 'process raft request' (duration: 268.373244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:20.467244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.057336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-09T22:35:20.467287Z","caller":"traceutil/trace.go:171","msg":"trace[35211907] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25056; }","duration":"254.141818ms","start":"2026-05-09T22:35:20.213125Z","end":"2026-05-09T22:35:20.467266Z","steps":["trace[35211907] 'agreement among raft nodes before linearized reading' (duration: 253.138798ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.619744Z","caller":"traceutil/trace.go:171","msg":"trace[228145234] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"139.807565ms","start":"2026-05-09T22:35:20.479907Z","end":"2026-05-09T22:35:20.619715Z","steps":["trace[228145234] 'process raft request' (duration: 139.59203ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.628476Z","caller":"traceutil/trace.go:171","msg":"trace[907326706] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"147.749934ms","start":"2026-05-09T22:35:20.480701Z","end":"2026-05-09T22:35:20.628451Z","steps":["trace[907326706] 'process raft request' (duration: 147.55229ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.628848Z","caller":"traceutil/trace.go:171","msg":"trace[1947033887] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"147.408917ms","start":"2026-05-09T22:35:20.481411Z","end":"2026-05-09T22:35:20.62882Z","steps":["trace[1947033887] 'process raft request' (duration: 146.980149ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.984405Z","caller":"traceutil/trace.go:171","msg":"trace[1744228343] linearizableReadLoop","detail":"{readStateIndex:25882; appliedIndex:25881; }","duration":"203.034702ms","start":"2026-05-09T22:35:20.781347Z","end":"2026-05-09T22:35:20.984382Z","steps":["trace[1744228343] 'read index received' (duration: 202.978201ms)","trace[1744228343] 'applied index is now lower than readState.Index' (duration: 55.461µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:20.984552Z","caller":"traceutil/trace.go:171","msg":"trace[662925938] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"279.781173ms","start":"2026-05-09T22:35:20.704731Z","end":"2026-05-09T22:35:20.984512Z","steps":["trace[662925938] 'process raft request' (duration: 279.483577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:20.98456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.199586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:35:20.984632Z","caller":"traceutil/trace.go:171","msg":"trace[536149347] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25061; }","duration":"203.324899ms","start":"2026-05-09T22:35:20.781294Z","end":"2026-05-09T22:35:20.984619Z","steps":["trace[536149347] 'agreement among raft nodes before linearized reading' (duration: 203.208977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:20.986183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.726014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:20.986271Z","caller":"traceutil/trace.go:171","msg":"trace[1921885555] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25064; }","duration":"152.855937ms","start":"2026-05-09T22:35:20.833395Z","end":"2026-05-09T22:35:20.986251Z","steps":["trace[1921885555] 'agreement among raft nodes before linearized reading' (duration: 152.702354ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:20.986368Z","caller":"traceutil/trace.go:171","msg":"trace[1065446841] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"110.322894ms","start":"2026-05-09T22:35:20.875983Z","end":"2026-05-09T22:35:20.986306Z","steps":["trace[1065446841] 'process raft request' (duration: 109.913626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:20.986513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.131011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-05-09T22:35:20.986587Z","caller":"traceutil/trace.go:171","msg":"trace[740842235] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25064; }","duration":"129.214072ms","start":"2026-05-09T22:35:20.857353Z","end":"2026-05-09T22:35:20.986567Z","steps":["trace[740842235] 'agreement among raft nodes before linearized reading' (duration: 129.024548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:25.384376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.782892ms","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-05-09T22:35:25.384475Z","caller":"traceutil/trace.go:171","msg":"trace[484707691] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25100; }","duration":"100.931516ms","start":"2026-05-09T22:35:25.283517Z","end":"2026-05-09T22:35:25.384448Z","steps":["trace[484707691] 'count revisions from in-memory index tree' (duration: 100.622329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:26.382873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.685775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:26.382979Z","caller":"traceutil/trace.go:171","msg":"trace[1370011314] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"347.102183ms","start":"2026-05-09T22:35:26.035857Z","end":"2026-05-09T22:35:26.382959Z","steps":["trace[1370011314] 'process raft request' (duration: 174.200324ms)","trace[1370011314] 'compare' (duration: 172.066773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:26.383043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:26.035833Z","time spent":"347.179545ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:26.457069Z","caller":"traceutil/trace.go:171","msg":"trace[77846922] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"253.178479ms","start":"2026-05-09T22:35:26.203862Z","end":"2026-05-09T22:35:26.45704Z","steps":["trace[77846922] 'process raft request' (duration: 253.000576ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:26.457184Z","caller":"traceutil/trace.go:171","msg":"trace[1951138444] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"252.588237ms","start":"2026-05-09T22:35:26.20455Z","end":"2026-05-09T22:35:26.457138Z","steps":["trace[1951138444] 'process raft request' (duration: 252.412194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:26.687861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.275268ms","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-05-09T22:35:26.687992Z","caller":"traceutil/trace.go:171","msg":"trace[636665660] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25105; }","duration":"192.441602ms","start":"2026-05-09T22:35:26.495525Z","end":"2026-05-09T22:35:26.687967Z","steps":["trace[636665660] 'range keys from in-memory index tree' (duration: 192.081385ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:27.138197Z","caller":"traceutil/trace.go:171","msg":"trace[1912488314] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"445.827764ms","start":"2026-05-09T22:35:26.69234Z","end":"2026-05-09T22:35:27.138167Z","steps":["trace[1912488314] 'process raft request' (duration: 445.64118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:27.138484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:26.692328Z","time spent":"445.977817ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:27.138577Z","caller":"traceutil/trace.go:171","msg":"trace[990227386] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"445.793764ms","start":"2026-05-09T22:35:26.692751Z","end":"2026-05-09T22:35:27.138545Z","steps":["trace[990227386] 'process raft request' (duration: 445.375605ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:27.138641Z","caller":"traceutil/trace.go:171","msg":"trace[907808487] linearizableReadLoop","detail":"{readStateIndex:25932; appliedIndex:25929; }","duration":"283.041009ms","start":"2026-05-09T22:35:26.855581Z","end":"2026-05-09T22:35:27.138622Z","steps":["trace[907808487] 'read index received' (duration: 282.452748ms)","trace[907808487] 'applied index is now lower than readState.Index' (duration: 587.171µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:27.138722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:26.692715Z","time spent":"445.929407ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:27.138735Z","caller":"traceutil/trace.go:171","msg":"trace[243569840] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"445.422836ms","start":"2026-05-09T22:35:26.693291Z","end":"2026-05-09T22:35:27.138713Z","steps":["trace[243569840] 'process raft request' (duration: 445.225502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:27.138854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:26.693275Z","time spent":"445.522959ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:27.138881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.290134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-09T22:35:27.138937Z","caller":"traceutil/trace.go:171","msg":"trace[2146910013] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25110; }","duration":"283.396626ms","start":"2026-05-09T22:35:26.855522Z","end":"2026-05-09T22:35:27.138919Z","steps":["trace[2146910013] 'agreement among raft nodes before linearized reading' (duration: 283.183302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:27.314258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.64877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:27.314493Z","caller":"traceutil/trace.go:171","msg":"trace[1527985902] linearizableReadLoop","detail":"{readStateIndex:25935; appliedIndex:25932; }","duration":"175.793727ms","start":"2026-05-09T22:35:27.138682Z","end":"2026-05-09T22:35:27.314476Z","steps":["trace[1527985902] 'read index received' (duration: 64.78007ms)","trace[1527985902] 'applied index is now lower than readState.Index' (duration: 111.012257ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:27.314803Z","caller":"traceutil/trace.go:171","msg":"trace[2119264715] transaction","detail":"{read_only:false; response_revision:25112; number_of_response:1; }","duration":"310.06437ms","start":"2026-05-09T22:35:27.00472Z","end":"2026-05-09T22:35:27.314784Z","steps":["trace[2119264715] 'process raft request' (duration: 309.638682ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:27.314806Z","caller":"traceutil/trace.go:171","msg":"trace[1605631164] transaction","detail":"{read_only:false; response_revision:25113; number_of_response:1; }","duration":"308.154762ms","start":"2026-05-09T22:35:27.006601Z","end":"2026-05-09T22:35:27.314756Z","steps":["trace[1605631164] 'process raft request' (duration: 307.827115ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:27.314808Z","caller":"traceutil/trace.go:171","msg":"trace[1079592605] transaction","detail":"{read_only:false; response_revision:25111; number_of_response:1; }","duration":"311.270104ms","start":"2026-05-09T22:35:27.003503Z","end":"2026-05-09T22:35:27.314773Z","steps":["trace[1079592605] 'process raft request' (duration: 200.015202ms)","trace[1079592605] 'compare' (duration: 110.519818ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:27.314881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:27.004699Z","time spent":"310.142662ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":532,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:27.314971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:27.006591Z","time spent":"308.285995ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:27.315004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:27.003477Z","time spent":"311.412477ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:27.315054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.895339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e5cf248b-d320-4217-9c38-27ef8c9fa661\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-05-09T22:35:27.31514Z","caller":"traceutil/trace.go:171","msg":"trace[892984137] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e5cf248b-d320-4217-9c38-27ef8c9fa661; range_end:; response_count:1; response_revision:25113; }","duration":"173.027492ms","start":"2026-05-09T22:35:27.14209Z","end":"2026-05-09T22:35:27.315117Z","steps":["trace[892984137] 'agreement among raft nodes before linearized reading' (duration: 172.760997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:27.315228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.987578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T22:35:27.315338Z","caller":"traceutil/trace.go:171","msg":"trace[524527233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25113; }","duration":"302.160382ms","start":"2026-05-09T22:35:27.013149Z","end":"2026-05-09T22:35:27.315309Z","steps":["trace[524527233] 'agreement among raft nodes before linearized reading' (duration: 301.720203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:27.315408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:27.013139Z","time spent":"302.253483ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T22:35:27.936067Z","caller":"traceutil/trace.go:171","msg":"trace[209320347] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"270.147441ms","start":"2026-05-09T22:35:27.665894Z","end":"2026-05-09T22:35:27.936041Z","steps":["trace[209320347] 'process raft request' (duration: 269.980467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:28.23608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.429681ms","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-05-09T22:35:28.236181Z","caller":"traceutil/trace.go:171","msg":"trace[2108704901] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25115; }","duration":"204.589644ms","start":"2026-05-09T22:35:28.031566Z","end":"2026-05-09T22:35:28.236155Z","steps":["trace[2108704901] 'count revisions from in-memory index tree' (duration: 204.286599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:28.719729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.829807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:28.719927Z","caller":"traceutil/trace.go:171","msg":"trace[1669159517] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"258.217ms","start":"2026-05-09T22:35:28.461676Z","end":"2026-05-09T22:35:28.719893Z","steps":["trace[1669159517] 'process raft request' (duration: 90.136878ms)","trace[1669159517] 'compare' (duration: 167.554192ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:28.720029Z","caller":"traceutil/trace.go:171","msg":"trace[1789807833] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"258.147038ms","start":"2026-05-09T22:35:28.461861Z","end":"2026-05-09T22:35:28.720008Z","steps":["trace[1789807833] 'process raft request' (duration: 257.970895ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:28.720042Z","caller":"traceutil/trace.go:171","msg":"trace[66583496] linearizableReadLoop","detail":"{readStateIndex:25940; appliedIndex:25938; }","duration":"222.471323ms","start":"2026-05-09T22:35:28.497541Z","end":"2026-05-09T22:35:28.720012Z","steps":["trace[66583496] 'read index received' (duration: 54.35888ms)","trace[66583496] 'applied index is now lower than readState.Index' (duration: 168.110882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:28.720153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.608835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:28.720178Z","caller":"traceutil/trace.go:171","msg":"trace[1242998926] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25119; }","duration":"222.674767ms","start":"2026-05-09T22:35:28.497494Z","end":"2026-05-09T22:35:28.720169Z","steps":["trace[1242998926] 'agreement among raft nodes before linearized reading' (duration: 222.622786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.092039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.264022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:29.092152Z","caller":"traceutil/trace.go:171","msg":"trace[853132752] linearizableReadLoop","detail":"{readStateIndex:25942; appliedIndex:25941; }","duration":"313.009368ms","start":"2026-05-09T22:35:28.779126Z","end":"2026-05-09T22:35:29.092135Z","steps":["trace[853132752] 'read index received' (duration: 44.500261ms)","trace[853132752] 'applied index is now lower than readState.Index' (duration: 268.507907ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:29.092221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.100061ms","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-05-09T22:35:29.092201Z","caller":"traceutil/trace.go:171","msg":"trace[672435339] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"353.958841ms","start":"2026-05-09T22:35:28.738203Z","end":"2026-05-09T22:35:29.092162Z","steps":["trace[672435339] 'process raft request' (duration: 85.487494ms)","trace[672435339] 'compare' (duration: 267.413736ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:29.092257Z","caller":"traceutil/trace.go:171","msg":"trace[109542618] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25120; }","duration":"313.175013ms","start":"2026-05-09T22:35:28.779071Z","end":"2026-05-09T22:35:29.092246Z","steps":["trace[109542618] 'agreement among raft nodes before linearized reading' (duration: 313.109422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.092296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:28.779049Z","time spent":"313.237194ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:35:29.092367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:28.73819Z","time spent":"354.079753ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:29.445474Z","caller":"traceutil/trace.go:171","msg":"trace[1071609326] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"299.054809ms","start":"2026-05-09T22:35:29.14639Z","end":"2026-05-09T22:35:29.445445Z","steps":["trace[1071609326] 'process raft request' (duration: 298.899586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.910305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.983475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:29.910474Z","caller":"traceutil/trace.go:171","msg":"trace[424107682] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"763.246122ms","start":"2026-05-09T22:35:29.147208Z","end":"2026-05-09T22:35:29.910454Z","steps":["trace[424107682] 'process raft request' (duration: 513.031982ms)","trace[424107682] 'compare' (duration: 249.331282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:29.910594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.147181Z","time spent":"763.378184ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:29.910965Z","caller":"traceutil/trace.go:171","msg":"trace[1122003444] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"763.62402ms","start":"2026-05-09T22:35:29.147305Z","end":"2026-05-09T22:35:29.910929Z","steps":["trace[1122003444] 'process raft request' (duration: 763.093619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.911246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.147294Z","time spent":"763.771343ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:29.911672Z","caller":"traceutil/trace.go:171","msg":"trace[419125458] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"591.442115ms","start":"2026-05-09T22:35:29.320207Z","end":"2026-05-09T22:35:29.911649Z","steps":["trace[419125458] 'process raft request' (duration: 590.580307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.911805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.320183Z","time spent":"591.551937ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:29.91187Z","caller":"traceutil/trace.go:171","msg":"trace[1525407100] linearizableReadLoop","detail":"{readStateIndex:25945; appliedIndex:25942; }","duration":"665.095804ms","start":"2026-05-09T22:35:29.246744Z","end":"2026-05-09T22:35:29.91184Z","steps":["trace[1525407100] 'read index received' (duration: 198.630965ms)","trace[1525407100] 'applied index is now lower than readState.Index' (duration: 466.463639ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:29.91192Z","caller":"traceutil/trace.go:171","msg":"trace[106057644] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"590.766042ms","start":"2026-05-09T22:35:29.321112Z","end":"2026-05-09T22:35:29.911878Z","steps":["trace[106057644] 'process raft request' (duration: 590.521507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.912116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.321088Z","time spent":"590.928486ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:29.912125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.359419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:29.91222Z","caller":"traceutil/trace.go:171","msg":"trace[1421065405] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25126; }","duration":"665.503352ms","start":"2026-05-09T22:35:29.246693Z","end":"2026-05-09T22:35:29.912197Z","steps":["trace[1421065405] 'agreement among raft nodes before linearized reading' (duration: 665.245597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.912243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.142904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T22:35:29.912312Z","caller":"traceutil/trace.go:171","msg":"trace[965783472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25126; }","duration":"519.266317ms","start":"2026-05-09T22:35:29.393029Z","end":"2026-05-09T22:35:29.912295Z","steps":["trace[965783472] 'agreement among raft nodes before linearized reading' (duration: 519.030092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.912366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.393006Z","time spent":"519.347609ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T22:35:29.91227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.246676Z","time spent":"665.583423ms","remote":"127.0.0.1:55226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:35:29.912583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.17202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:29.911913Z","caller":"traceutil/trace.go:171","msg":"trace[1049220862] transaction","detail":"{read_only:false; response_revision:25126; number_of_response:1; }","duration":"589.615459ms","start":"2026-05-09T22:35:29.32226Z","end":"2026-05-09T22:35:29.911875Z","steps":["trace[1049220862] 'process raft request' (duration: 589.508557ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:29.912623Z","caller":"traceutil/trace.go:171","msg":"trace[1566355118] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:25126; }","duration":"434.214851ms","start":"2026-05-09T22:35:29.478397Z","end":"2026-05-09T22:35:29.912612Z","steps":["trace[1566355118] 'agreement among raft nodes before linearized reading' (duration: 434.14076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:29.912669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.478322Z","time spent":"434.333633ms","remote":"127.0.0.1:44018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:35:29.91266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.657691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:35:29.912747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:29.322247Z","time spent":"590.410615ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:29.912776Z","caller":"traceutil/trace.go:171","msg":"trace[1674499968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25126; }","duration":"289.833534ms","start":"2026-05-09T22:35:29.62292Z","end":"2026-05-09T22:35:29.912753Z","steps":["trace[1674499968] 'agreement among raft nodes before linearized reading' (duration: 289.570879ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:30.224077Z","caller":"traceutil/trace.go:171","msg":"trace[575893341] linearizableReadLoop","detail":"{readStateIndex:25950; appliedIndex:25949; }","duration":"259.490465ms","start":"2026-05-09T22:35:29.964568Z","end":"2026-05-09T22:35:30.224058Z","steps":["trace[575893341] 'read index received' (duration: 229.321231ms)","trace[575893341] 'applied index is now lower than readState.Index' (duration: 30.168254ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:30.22414Z","caller":"traceutil/trace.go:171","msg":"trace[128680543] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"282.900386ms","start":"2026-05-09T22:35:29.941189Z","end":"2026-05-09T22:35:30.22409Z","steps":["trace[128680543] 'process raft request' (duration: 252.766082ms)","trace[128680543] 'compare' (duration: 30.008321ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:30.224226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.647768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-09T22:35:30.224252Z","caller":"traceutil/trace.go:171","msg":"trace[1358306221] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25128; }","duration":"259.70796ms","start":"2026-05-09T22:35:29.964537Z","end":"2026-05-09T22:35:30.224245Z","steps":["trace[1358306221] 'agreement among raft nodes before linearized reading' (duration: 259.594298ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:30.544221Z","caller":"traceutil/trace.go:171","msg":"trace[465382143] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"123.329234ms","start":"2026-05-09T22:35:30.420863Z","end":"2026-05-09T22:35:30.544192Z","steps":["trace[465382143] 'process raft request' (duration: 122.860604ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:31.066482Z","caller":"traceutil/trace.go:171","msg":"trace[1646968361] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"340.833549ms","start":"2026-05-09T22:35:30.725615Z","end":"2026-05-09T22:35:31.066448Z","steps":["trace[1646968361] 'process raft request' (duration: 328.518162ms)","trace[1646968361] 'compare' (duration: 12.085972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:31.066636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:30.725586Z","time spent":"340.966791ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:31.066449Z","caller":"traceutil/trace.go:171","msg":"trace[1058946238] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"339.44276ms","start":"2026-05-09T22:35:30.726983Z","end":"2026-05-09T22:35:31.066426Z","steps":["trace[1058946238] 'process raft request' (duration: 339.358649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:31.067128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:30.726958Z","time spent":"340.085692ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:31.273934Z","caller":"traceutil/trace.go:171","msg":"trace[2006455967] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"172.723116ms","start":"2026-05-09T22:35:31.101156Z","end":"2026-05-09T22:35:31.273879Z","steps":["trace[2006455967] 'process raft request' (duration: 158.561221ms)","trace[2006455967] 'compare' (duration: 13.631614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:31.638403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.231386ms","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-05-09T22:35:31.638643Z","caller":"traceutil/trace.go:171","msg":"trace[357877151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25135; }","duration":"189.42097ms","start":"2026-05-09T22:35:31.449143Z","end":"2026-05-09T22:35:31.638564Z","steps":["trace[357877151] 'range keys from in-memory index tree' (duration: 189.011951ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:38.612036Z","caller":"traceutil/trace.go:171","msg":"trace[38412768] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"342.673995ms","start":"2026-05-09T22:35:38.269337Z","end":"2026-05-09T22:35:38.612011Z","steps":["trace[38412768] 'process raft request' (duration: 342.50031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:38.612204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:38.269301Z","time spent":"342.823108ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:38.917082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.023053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:38.917147Z","caller":"traceutil/trace.go:171","msg":"trace[1790395715] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25189; }","duration":"209.124725ms","start":"2026-05-09T22:35:38.708005Z","end":"2026-05-09T22:35:38.91713Z","steps":["trace[1790395715] 'count revisions from in-memory index tree' (duration: 208.962651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:38.91718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.223679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:38.917253Z","caller":"traceutil/trace.go:171","msg":"trace[823921347] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25189; }","duration":"257.336493ms","start":"2026-05-09T22:35:38.659897Z","end":"2026-05-09T22:35:38.917234Z","steps":["trace[823921347] 'range keys from in-memory index tree' (duration: 257.073846ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:38.928868Z","caller":"traceutil/trace.go:171","msg":"trace[1584587430] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"152.125802ms","start":"2026-05-09T22:35:38.776726Z","end":"2026-05-09T22:35:38.928852Z","steps":["trace[1584587430] 'process raft request' (duration: 151.692082ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:39.608851Z","caller":"traceutil/trace.go:171","msg":"trace[581216609] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"501.998231ms","start":"2026-05-09T22:35:39.106833Z","end":"2026-05-09T22:35:39.608831Z","steps":["trace[581216609] 'process raft request' (duration: 501.765856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:39.608984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.106807Z","time spent":"502.097773ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:39.631304Z","caller":"traceutil/trace.go:171","msg":"trace[1807546962] linearizableReadLoop","detail":"{readStateIndex:26016; appliedIndex:26014; }","duration":"367.243847ms","start":"2026-05-09T22:35:39.264038Z","end":"2026-05-09T22:35:39.631282Z","steps":["trace[1807546962] 'read index received' (duration: 344.550472ms)","trace[1807546962] 'applied index is now lower than readState.Index' (duration: 22.692515ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:39.631408Z","caller":"traceutil/trace.go:171","msg":"trace[1558287411] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"523.330808ms","start":"2026-05-09T22:35:39.108069Z","end":"2026-05-09T22:35:39.6314Z","steps":["trace[1558287411] 'process raft request' (duration: 523.059203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:39.63153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.108023Z","time spent":"523.4074ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:39.631797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.308477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:35:39.631932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.898212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:39.631967Z","caller":"traceutil/trace.go:171","msg":"trace[547703919] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:25192; }","duration":"367.975442ms","start":"2026-05-09T22:35:39.263984Z","end":"2026-05-09T22:35:39.631959Z","steps":["trace[547703919] 'agreement among raft nodes before linearized reading' (duration: 367.913261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:39.631976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.679973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T22:35:39.631991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.263921Z","time spent":"368.064465ms","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-05-09T22:35:39.632007Z","caller":"traceutil/trace.go:171","msg":"trace[311330089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25192; }","duration":"321.746925ms","start":"2026-05-09T22:35:39.310248Z","end":"2026-05-09T22:35:39.631995Z","steps":["trace[311330089] 'agreement among raft nodes before linearized reading' (duration: 321.656203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:39.632035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.310229Z","time spent":"321.799336ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-09T22:35:39.631945Z","caller":"traceutil/trace.go:171","msg":"trace[1888538306] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25192; }","duration":"338.46939ms","start":"2026-05-09T22:35:39.293448Z","end":"2026-05-09T22:35:39.631918Z","steps":["trace[1888538306] 'agreement among raft nodes before linearized reading' (duration: 338.262756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:39.632173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.293428Z","time spent":"338.723735ms","remote":"127.0.0.1:40208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:35:39.631866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.336449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:35:39.632539Z","caller":"traceutil/trace.go:171","msg":"trace[416188628] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:25192; }","duration":"190.045952ms","start":"2026-05-09T22:35:39.442475Z","end":"2026-05-09T22:35:39.632521Z","steps":["trace[416188628] 'agreement among raft nodes before linearized reading' (duration: 189.331358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.34087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.54983ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:40.340963Z","caller":"traceutil/trace.go:171","msg":"trace[230789136] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25192; }","duration":"124.654502ms","start":"2026-05-09T22:35:40.216295Z","end":"2026-05-09T22:35:40.340949Z","steps":["trace[230789136] 'range keys from in-memory index tree' (duration: 124.53622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.341252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.1753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:40.341448Z","caller":"traceutil/trace.go:171","msg":"trace[915371577] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"610.717612ms","start":"2026-05-09T22:35:39.730714Z","end":"2026-05-09T22:35:40.341432Z","steps":["trace[915371577] 'process raft request' (duration: 610.650351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.341518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.73069Z","time spent":"610.797593ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:40.342012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.105132ms","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-05-09T22:35:40.34145Z","caller":"traceutil/trace.go:171","msg":"trace[1966268592] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"705.375311ms","start":"2026-05-09T22:35:39.636033Z","end":"2026-05-09T22:35:40.341408Z","steps":["trace[1966268592] 'process raft request' (duration: 294.974107ms)","trace[1966268592] 'compare' (duration: 410.028686ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:40.342089Z","caller":"traceutil/trace.go:171","msg":"trace[1932504770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25194; }","duration":"653.220375ms","start":"2026-05-09T22:35:39.688851Z","end":"2026-05-09T22:35:40.342072Z","steps":["trace[1932504770] 'agreement among raft nodes before linearized reading' (duration: 652.99615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.34213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.688818Z","time spent":"653.303676ms","remote":"127.0.0.1:57634","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-05-09T22:35:40.342211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.636021Z","time spent":"706.078595ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:40.342063Z","caller":"traceutil/trace.go:171","msg":"trace[2103241536] linearizableReadLoop","detail":"{readStateIndex:26017; appliedIndex:26016; }","duration":"652.614823ms","start":"2026-05-09T22:35:39.688894Z","end":"2026-05-09T22:35:40.341508Z","steps":["trace[2103241536] 'read index received' (duration: 242.204939ms)","trace[2103241536] 'applied index is now lower than readState.Index' (duration: 410.405754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:35:40.70595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.703545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:35:40.706211Z","caller":"traceutil/trace.go:171","msg":"trace[1196373551] linearizableReadLoop","detail":"{readStateIndex:26021; appliedIndex:26018; }","duration":"363.806639ms","start":"2026-05-09T22:35:40.342393Z","end":"2026-05-09T22:35:40.7062Z","steps":["trace[1196373551] 'read index received' (duration: 183.802828ms)","trace[1196373551] 'applied index is now lower than readState.Index' (duration: 180.003111ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:40.706302Z","caller":"traceutil/trace.go:171","msg":"trace[290470743] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"739.943485ms","start":"2026-05-09T22:35:39.966351Z","end":"2026-05-09T22:35:40.706295Z","steps":["trace[290470743] 'process raft request' (duration: 739.68969ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:40.706355Z","caller":"traceutil/trace.go:171","msg":"trace[1763753206] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"740.027596ms","start":"2026-05-09T22:35:39.966298Z","end":"2026-05-09T22:35:40.706326Z","steps":["trace[1763753206] 'process raft request' (duration: 559.887723ms)","trace[1763753206] 'compare' (duration: 179.524371ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:40.706413Z","caller":"traceutil/trace.go:171","msg":"trace[2117320078] transaction","detail":"{read_only:false; response_revision:25197; number_of_response:1; }","duration":"740.010056ms","start":"2026-05-09T22:35:39.966396Z","end":"2026-05-09T22:35:40.706406Z","steps":["trace[2117320078] 'process raft request' (duration: 739.71774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.706469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.966376Z","time spent":"740.055826ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:40.706506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.966273Z","time spent":"740.154278ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:40.706384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.96633Z","time spent":"739.994995ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:35:40.707011Z","caller":"traceutil/trace.go:171","msg":"trace[1464432169] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"359.498902ms","start":"2026-05-09T22:35:40.347488Z","end":"2026-05-09T22:35:40.706987Z","steps":["trace[1464432169] 'process raft request' (duration: 358.684246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.707174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:40.347411Z","time spent":"359.654125ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:40.710083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.601128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:35:40.710152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.822182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-09T22:35:40.710168Z","caller":"traceutil/trace.go:171","msg":"trace[1815565427] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25198; }","duration":"774.754342ms","start":"2026-05-09T22:35:39.935394Z","end":"2026-05-09T22:35:40.710148Z","steps":["trace[1815565427] 'agreement among raft nodes before linearized reading' (duration: 771.319193ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:40.710212Z","caller":"traceutil/trace.go:171","msg":"trace[1616045201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25198; }","duration":"743.921934ms","start":"2026-05-09T22:35:39.966276Z","end":"2026-05-09T22:35:40.710198Z","steps":["trace[1616045201] 'agreement among raft nodes before linearized reading' (duration: 740.430364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.710222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.935375Z","time spent":"774.837145ms","remote":"127.0.0.1:42458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-09T22:35:40.71025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.966259Z","time spent":"743.984505ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T22:35:40.71039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.479552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"warn","ts":"2026-05-09T22:35:40.710402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.009515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-09T22:35:40.710437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.332312ms","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-05-09T22:35:40.710462Z","caller":"traceutil/trace.go:171","msg":"trace[2049969393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25198; }","duration":"736.380843ms","start":"2026-05-09T22:35:39.974073Z","end":"2026-05-09T22:35:40.710454Z","steps":["trace[2049969393] 'agreement among raft nodes before linearized reading' (duration: 732.627247ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:40.710456Z","caller":"traceutil/trace.go:171","msg":"trace[2113097195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25198; }","duration":"728.109587ms","start":"2026-05-09T22:35:39.982334Z","end":"2026-05-09T22:35:40.710443Z","steps":["trace[2113097195] 'agreement among raft nodes before linearized reading' (duration: 724.356211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.710491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.974065Z","time spent":"736.419424ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T22:35:40.710498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.982313Z","time spent":"728.177908ms","remote":"127.0.0.1:57634","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-05-09T22:35:40.710887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.484089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-09T22:35:40.710936Z","caller":"traceutil/trace.go:171","msg":"trace[581665650] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25200; }","duration":"305.56035ms","start":"2026-05-09T22:35:40.405364Z","end":"2026-05-09T22:35:40.710925Z","steps":["trace[581665650] 'agreement among raft nodes before linearized reading' (duration: 305.414927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.71096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:40.405352Z","time spent":"305.60164ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-09T22:35:40.711339Z","caller":"traceutil/trace.go:171","msg":"trace[1794167094] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25198; }","duration":"845.586073ms","start":"2026-05-09T22:35:39.864862Z","end":"2026-05-09T22:35:40.710448Z","steps":["trace[1794167094] 'agreement among raft nodes before linearized reading' (duration: 841.856228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:40.711424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:39.864844Z","time spent":"846.560253ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1805,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-05-09T22:35:42.126425Z","caller":"traceutil/trace.go:171","msg":"trace[922106801] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"125.709772ms","start":"2026-05-09T22:35:42.000693Z","end":"2026-05-09T22:35:42.126403Z","steps":["trace[922106801] 'process raft request' (duration: 125.58453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:49.414908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.708561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:49.415006Z","caller":"traceutil/trace.go:171","msg":"trace[832000613] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25352; }","duration":"106.858274ms","start":"2026-05-09T22:35:49.308118Z","end":"2026-05-09T22:35:49.414976Z","steps":["trace[832000613] 'count revisions from in-memory index tree' (duration: 106.621019ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:49.759603Z","caller":"traceutil/trace.go:171","msg":"trace[49411519] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"118.096519ms","start":"2026-05-09T22:35:49.641477Z","end":"2026-05-09T22:35:49.759573Z","steps":["trace[49411519] 'process raft request' (duration: 117.925646ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:35:50.031913Z","caller":"traceutil/trace.go:171","msg":"trace[61050489] linearizableReadLoop","detail":"{readStateIndex:26181; appliedIndex:26180; }","duration":"258.600567ms","start":"2026-05-09T22:35:49.773274Z","end":"2026-05-09T22:35:50.031875Z","steps":["trace[61050489] 'read index received' (duration: 250.292021ms)","trace[61050489] 'applied index is now lower than readState.Index' (duration: 8.306216ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:35:50.032046Z","caller":"traceutil/trace.go:171","msg":"trace[1334288334] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"362.036272ms","start":"2026-05-09T22:35:49.669988Z","end":"2026-05-09T22:35:50.032024Z","steps":["trace[1334288334] 'process raft request' (duration: 353.615123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:50.032155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:35:49.669953Z","time spent":"362.135455ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:35:50.032172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.865682ms","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":"info","ts":"2026-05-09T22:35:50.032237Z","caller":"traceutil/trace.go:171","msg":"trace[732201285] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25354; }","duration":"258.986235ms","start":"2026-05-09T22:35:49.773229Z","end":"2026-05-09T22:35:50.032215Z","steps":["trace[732201285] 'agreement among raft nodes before linearized reading' (duration: 258.838042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:50.032258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.622611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:50.032345Z","caller":"traceutil/trace.go:171","msg":"trace[199005176] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25354; }","duration":"161.747274ms","start":"2026-05-09T22:35:49.870565Z","end":"2026-05-09T22:35:50.032312Z","steps":["trace[199005176] 'agreement among raft nodes before linearized reading' (duration: 161.592001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:35:50.273469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.390984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:35:50.273538Z","caller":"traceutil/trace.go:171","msg":"trace[1234847429] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25354; }","duration":"110.511766ms","start":"2026-05-09T22:35:50.163011Z","end":"2026-05-09T22:35:50.273523Z","steps":["trace[1234847429] 'count revisions from in-memory index tree' (duration: 110.309192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:05.056039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.845249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:05.056198Z","caller":"traceutil/trace.go:171","msg":"trace[224142551] transaction","detail":"{read_only:false; response_revision:25470; number_of_response:1; }","duration":"221.460523ms","start":"2026-05-09T22:36:04.834713Z","end":"2026-05-09T22:36:05.056174Z","steps":["trace[224142551] 'process raft request' (duration: 24.376959ms)","trace[224142551] 'compare' (duration: 195.970042ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:05.064448Z","caller":"traceutil/trace.go:171","msg":"trace[1983434527] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"229.581126ms","start":"2026-05-09T22:36:04.834834Z","end":"2026-05-09T22:36:05.064415Z","steps":["trace[1983434527] 'process raft request' (duration: 229.29428ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:05.064474Z","caller":"traceutil/trace.go:171","msg":"trace[580239187] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"228.144696ms","start":"2026-05-09T22:36:04.836294Z","end":"2026-05-09T22:36:05.064438Z","steps":["trace[580239187] 'process raft request' (duration: 228.040504ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:05.064592Z","caller":"traceutil/trace.go:171","msg":"trace[1748960165] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"227.352331ms","start":"2026-05-09T22:36:04.837224Z","end":"2026-05-09T22:36:05.064576Z","steps":["trace[1748960165] 'process raft request' (duration: 227.161677ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:05.149371Z","caller":"traceutil/trace.go:171","msg":"trace[1528638439] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"133.970218ms","start":"2026-05-09T22:36:05.015379Z","end":"2026-05-09T22:36:05.149349Z","steps":["trace[1528638439] 'process raft request' (duration: 133.438786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:05.440112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.142109ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-05-09T22:36:05.753461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.836164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:05.753539Z","caller":"traceutil/trace.go:171","msg":"trace[404986424] linearizableReadLoop","detail":"{readStateIndex:26307; appliedIndex:26305; }","duration":"227.375462ms","start":"2026-05-09T22:36:05.526151Z","end":"2026-05-09T22:36:05.753527Z","steps":["trace[404986424] 'read index received' (duration: 873.988µs)","trace[404986424] 'applied index is now lower than readState.Index' (duration: 226.500504ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:05.753603Z","caller":"traceutil/trace.go:171","msg":"trace[1109478386] transaction","detail":"{read_only:false; response_revision:25476; number_of_response:1; }","duration":"238.069996ms","start":"2026-05-09T22:36:05.515525Z","end":"2026-05-09T22:36:05.753595Z","steps":["trace[1109478386] 'process raft request' (duration: 123.748542ms)","trace[1109478386] 'compare' (duration: 113.62555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:05.753709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.716208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-09T22:36:05.753742Z","caller":"traceutil/trace.go:171","msg":"trace[505850213] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25476; }","duration":"224.781109ms","start":"2026-05-09T22:36:05.528951Z","end":"2026-05-09T22:36:05.753732Z","steps":["trace[505850213] 'agreement among raft nodes before linearized reading' (duration: 224.658126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:05.75373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.543735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:36:05.753772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.385366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:05.753806Z","caller":"traceutil/trace.go:171","msg":"trace[1751051928] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25476; }","duration":"227.686428ms","start":"2026-05-09T22:36:05.526098Z","end":"2026-05-09T22:36:05.753785Z","steps":["trace[1751051928] 'agreement among raft nodes before linearized reading' (duration: 227.530705ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:05.753859Z","caller":"traceutil/trace.go:171","msg":"trace[286665072] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:25476; }","duration":"144.530049ms","start":"2026-05-09T22:36:05.609307Z","end":"2026-05-09T22:36:05.753837Z","steps":["trace[286665072] 'agreement among raft nodes before linearized reading' (duration: 144.348655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:06.068379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.253856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:06.068612Z","caller":"traceutil/trace.go:171","msg":"trace[1648235996] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"249.950566ms","start":"2026-05-09T22:36:05.818634Z","end":"2026-05-09T22:36:06.068584Z","steps":["trace[1648235996] 'process raft request' (duration: 90.393375ms)","trace[1648235996] 'compare' (duration: 159.094542ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:06.502127Z","caller":"traceutil/trace.go:171","msg":"trace[150963732] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"413.894213ms","start":"2026-05-09T22:36:06.088213Z","end":"2026-05-09T22:36:06.502107Z","steps":["trace[150963732] 'process raft request' (duration: 413.75141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:06.50227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:06.08819Z","time spent":"413.995455ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:36:06.710205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.895376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:06.710323Z","caller":"traceutil/trace.go:171","msg":"trace[442912613] linearizableReadLoop","detail":"{readStateIndex:26311; appliedIndex:26308; }","duration":"446.115019ms","start":"2026-05-09T22:36:06.264198Z","end":"2026-05-09T22:36:06.710313Z","steps":["trace[442912613] 'read index received' (duration: 237.79416ms)","trace[442912613] 'applied index is now lower than readState.Index' (duration: 208.320369ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:06.710381Z","caller":"traceutil/trace.go:171","msg":"trace[1746295854] transaction","detail":"{read_only:false; response_revision:25480; number_of_response:1; }","duration":"536.81641ms","start":"2026-05-09T22:36:06.173559Z","end":"2026-05-09T22:36:06.710376Z","steps":["trace[1746295854] 'process raft request' (duration: 536.722758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:06.710419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:06.173532Z","time spent":"536.86838ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:36:06.710542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.354864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-09T22:36:06.710556Z","caller":"traceutil/trace.go:171","msg":"trace[218246834] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25480; }","duration":"446.406436ms","start":"2026-05-09T22:36:06.264145Z","end":"2026-05-09T22:36:06.710552Z","steps":["trace[218246834] 'agreement among raft nodes before linearized reading' (duration: 446.337375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:06.710569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:06.264126Z","time spent":"446.440236ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T22:36:06.710705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.613614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T22:36:06.710725Z","caller":"traceutil/trace.go:171","msg":"trace[494363444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25480; }","duration":"199.632884ms","start":"2026-05-09T22:36:06.511087Z","end":"2026-05-09T22:36:06.710719Z","steps":["trace[494363444] 'agreement among raft nodes before linearized reading' (duration: 199.582253ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:06.710739Z","caller":"traceutil/trace.go:171","msg":"trace[1633718264] transaction","detail":"{read_only:false; response_revision:25479; number_of_response:1; }","duration":"571.383952ms","start":"2026-05-09T22:36:06.139289Z","end":"2026-05-09T22:36:06.710673Z","steps":["trace[1633718264] 'process raft request' (duration: 445.961496ms)","trace[1633718264] 'compare' (duration: 124.796034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:06.710854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:06.13926Z","time spent":"571.537366ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:36:06.878665Z","caller":"traceutil/trace.go:171","msg":"trace[1064986010] linearizableReadLoop","detail":"{readStateIndex:26312; appliedIndex:26311; }","duration":"114.276343ms","start":"2026-05-09T22:36:06.764371Z","end":"2026-05-09T22:36:06.878647Z","steps":["trace[1064986010] 'read index received' (duration: 92.068367ms)","trace[1064986010] 'applied index is now lower than readState.Index' (duration: 22.206896ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:06.878827Z","caller":"traceutil/trace.go:171","msg":"trace[607868234] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"162.420348ms","start":"2026-05-09T22:36:06.716397Z","end":"2026-05-09T22:36:06.878818Z","steps":["trace[607868234] 'process raft request' (duration: 140.016568ms)","trace[607868234] 'compare' (duration: 22.161985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:06.878965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.552448ms","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-05-09T22:36:06.879084Z","caller":"traceutil/trace.go:171","msg":"trace[700584545] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25481; }","duration":"114.728452ms","start":"2026-05-09T22:36:06.76433Z","end":"2026-05-09T22:36:06.879059Z","steps":["trace[700584545] 'agreement among raft nodes before linearized reading' (duration: 114.495677ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.063721Z","caller":"traceutil/trace.go:171","msg":"trace[1710858192] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"263.279232ms","start":"2026-05-09T22:36:06.800416Z","end":"2026-05-09T22:36:07.063696Z","steps":["trace[1710858192] 'process raft request' (duration: 263.070148ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.064126Z","caller":"traceutil/trace.go:171","msg":"trace[190792263] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"263.620269ms","start":"2026-05-09T22:36:06.800486Z","end":"2026-05-09T22:36:07.064106Z","steps":["trace[190792263] 'process raft request' (duration: 263.16074ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.064275Z","caller":"traceutil/trace.go:171","msg":"trace[1558308619] linearizableReadLoop","detail":"{readStateIndex:26317; appliedIndex:26312; }","duration":"185.533662ms","start":"2026-05-09T22:36:06.878709Z","end":"2026-05-09T22:36:07.064243Z","steps":["trace[1558308619] 'read index received' (duration: 184.789177ms)","trace[1558308619] 'applied index is now lower than readState.Index' (duration: 742.905µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:07.064314Z","caller":"traceutil/trace.go:171","msg":"trace[434810321] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"262.067968ms","start":"2026-05-09T22:36:06.80222Z","end":"2026-05-09T22:36:07.064288Z","steps":["trace[434810321] 'process raft request' (duration: 261.918515ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.064458Z","caller":"traceutil/trace.go:171","msg":"trace[2046749932] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"260.811743ms","start":"2026-05-09T22:36:06.803631Z","end":"2026-05-09T22:36:07.064442Z","steps":["trace[2046749932] 'process raft request' (duration: 260.557268ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.064486Z","caller":"traceutil/trace.go:171","msg":"trace[970728218] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"262.574478ms","start":"2026-05-09T22:36:06.8019Z","end":"2026-05-09T22:36:07.064474Z","steps":["trace[970728218] 'process raft request' (duration: 262.049058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.064604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.914179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:07.064658Z","caller":"traceutil/trace.go:171","msg":"trace[2133868591] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25486; }","duration":"182.004112ms","start":"2026-05-09T22:36:06.882642Z","end":"2026-05-09T22:36:07.064646Z","steps":["trace[2133868591] 'agreement among raft nodes before linearized reading' (duration: 181.90839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.064778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.57778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"warn","ts":"2026-05-09T22:36:07.06483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.252358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:07.06488Z","caller":"traceutil/trace.go:171","msg":"trace[1910819144] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25486; }","duration":"212.358169ms","start":"2026-05-09T22:36:06.85251Z","end":"2026-05-09T22:36:07.064868Z","steps":["trace[1910819144] 'agreement among raft nodes before linearized reading' (duration: 212.270577ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.064844Z","caller":"traceutil/trace.go:171","msg":"trace[155966797] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:25486; }","duration":"135.676691ms","start":"2026-05-09T22:36:06.929152Z","end":"2026-05-09T22:36:07.064829Z","steps":["trace[155966797] 'agreement among raft nodes before linearized reading' (duration: 135.504118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.328962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.816656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:07.329094Z","caller":"traceutil/trace.go:171","msg":"trace[1439463502] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"257.122119ms","start":"2026-05-09T22:36:07.071943Z","end":"2026-05-09T22:36:07.329065Z","steps":["trace[1439463502] 'process raft request' (duration: 119.080289ms)","trace[1439463502] 'compare' (duration: 137.57878ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:07.330384Z","caller":"traceutil/trace.go:171","msg":"trace[31018052] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"257.319262ms","start":"2026-05-09T22:36:07.073044Z","end":"2026-05-09T22:36:07.330364Z","steps":["trace[31018052] 'process raft request' (duration: 257.273351ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.330415Z","caller":"traceutil/trace.go:171","msg":"trace[98327527] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"258.237ms","start":"2026-05-09T22:36:07.072137Z","end":"2026-05-09T22:36:07.330374Z","steps":["trace[98327527] 'process raft request' (duration: 258.051357ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.330569Z","caller":"traceutil/trace.go:171","msg":"trace[1600771863] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"258.304912ms","start":"2026-05-09T22:36:07.072257Z","end":"2026-05-09T22:36:07.330561Z","steps":["trace[1600771863] 'process raft request' (duration: 258.032897ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.330611Z","caller":"traceutil/trace.go:171","msg":"trace[2016724542] linearizableReadLoop","detail":"{readStateIndex:26321; appliedIndex:26317; }","duration":"246.410073ms","start":"2026-05-09T22:36:07.084165Z","end":"2026-05-09T22:36:07.330575Z","steps":["trace[2016724542] 'read index received' (duration: 106.879294ms)","trace[2016724542] 'applied index is now lower than readState.Index' (duration: 139.529119ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:07.330717Z","caller":"traceutil/trace.go:171","msg":"trace[1079396260] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"175.905549ms","start":"2026-05-09T22:36:07.154805Z","end":"2026-05-09T22:36:07.330711Z","steps":["trace[1079396260] 'process raft request' (duration: 175.532372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.330878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.693179ms","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-05-09T22:36:07.330929Z","caller":"traceutil/trace.go:171","msg":"trace[1968089362] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25491; }","duration":"246.778541ms","start":"2026-05-09T22:36:07.084134Z","end":"2026-05-09T22:36:07.330912Z","steps":["trace[1968089362] 'agreement among raft nodes before linearized reading' (duration: 246.607678ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.633016Z","caller":"traceutil/trace.go:171","msg":"trace[851779742] linearizableReadLoop","detail":"{readStateIndex:26323; appliedIndex:26322; }","duration":"196.774048ms","start":"2026-05-09T22:36:07.436224Z","end":"2026-05-09T22:36:07.632998Z","steps":["trace[851779742] 'read index received' (duration: 153.968339ms)","trace[851779742] 'applied index is now lower than readState.Index' (duration: 42.804839ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:07.633193Z","caller":"traceutil/trace.go:171","msg":"trace[705900122] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"289.735572ms","start":"2026-05-09T22:36:07.343449Z","end":"2026-05-09T22:36:07.633184Z","steps":["trace[705900122] 'process raft request' (duration: 246.900543ms)","trace[705900122] 'compare' (duration: 42.552964ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:07.633346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.120304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:07.633371Z","caller":"traceutil/trace.go:171","msg":"trace[415568822] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:25492; }","duration":"197.228417ms","start":"2026-05-09T22:36:07.436136Z","end":"2026-05-09T22:36:07.633364Z","steps":["trace[415568822] 'agreement among raft nodes before linearized reading' (duration: 197.168696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.633481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.390137ms","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-05-09T22:36:07.633503Z","caller":"traceutil/trace.go:171","msg":"trace[1645645725] 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:25492; }","duration":"158.435919ms","start":"2026-05-09T22:36:07.47506Z","end":"2026-05-09T22:36:07.633496Z","steps":["trace[1645645725] 'agreement among raft nodes before linearized reading' (duration: 158.399108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.902659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.347504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:07.902807Z","caller":"traceutil/trace.go:171","msg":"trace[1050355423] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"263.414725ms","start":"2026-05-09T22:36:07.639334Z","end":"2026-05-09T22:36:07.902749Z","steps":["trace[1050355423] 'process raft request' (duration: 129.886797ms)","trace[1050355423] 'compare' (duration: 133.10838ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:07.941693Z","caller":"traceutil/trace.go:171","msg":"trace[2130112947] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"300.054949ms","start":"2026-05-09T22:36:07.641616Z","end":"2026-05-09T22:36:07.941671Z","steps":["trace[2130112947] 'process raft request' (duration: 299.941427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.941845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:07.641585Z","time spent":"300.181252ms","remote":"127.0.0.1:57648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:36:07.941835Z","caller":"traceutil/trace.go:171","msg":"trace[1518773278] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"300.58133ms","start":"2026-05-09T22:36:07.641075Z","end":"2026-05-09T22:36:07.941656Z","steps":["trace[1518773278] 'process raft request' (duration: 300.338525ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:07.941892Z","caller":"traceutil/trace.go:171","msg":"trace[2123886372] linearizableReadLoop","detail":"{readStateIndex:26326; appliedIndex:26323; }","duration":"298.414886ms","start":"2026-05-09T22:36:07.643449Z","end":"2026-05-09T22:36:07.941864Z","steps":["trace[2123886372] 'read index received' (duration: 125.862055ms)","trace[2123886372] 'applied index is now lower than readState.Index' (duration: 172.550411ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:07.94198Z","caller":"traceutil/trace.go:171","msg":"trace[1832710524] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"292.850315ms","start":"2026-05-09T22:36:07.649119Z","end":"2026-05-09T22:36:07.941969Z","steps":["trace[1832710524] 'process raft request' (duration: 292.482368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:07.942073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.613689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T22:36:07.942027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:07.641064Z","time spent":"300.853665ms","remote":"127.0.0.1:57648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:36:07.942141Z","caller":"traceutil/trace.go:171","msg":"trace[998024925] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25496; }","duration":"298.706652ms","start":"2026-05-09T22:36:07.643414Z","end":"2026-05-09T22:36:07.942121Z","steps":["trace[998024925] 'agreement among raft nodes before linearized reading' (duration: 298.59222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:17.603184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.965967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-09T22:36:17.603266Z","caller":"traceutil/trace.go:171","msg":"trace[413999794] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25569; }","duration":"135.10235ms","start":"2026-05-09T22:36:17.468143Z","end":"2026-05-09T22:36:17.603246Z","steps":["trace[413999794] 'range keys from in-memory index tree' (duration: 134.809774ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:17.603759Z","caller":"traceutil/trace.go:171","msg":"trace[2123677610] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"135.0785ms","start":"2026-05-09T22:36:17.468642Z","end":"2026-05-09T22:36:17.603721Z","steps":["trace[2123677610] 'process raft request' (duration: 130.825255ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:17.604722Z","caller":"traceutil/trace.go:171","msg":"trace[685905783] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"120.254393ms","start":"2026-05-09T22:36:17.48445Z","end":"2026-05-09T22:36:17.604705Z","steps":["trace[685905783] 'process raft request' (duration: 120.217182ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:17.604878Z","caller":"traceutil/trace.go:171","msg":"trace[237508215] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"120.428486ms","start":"2026-05-09T22:36:17.484429Z","end":"2026-05-09T22:36:17.604857Z","steps":["trace[237508215] 'process raft request' (duration: 120.199732ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:17.605031Z","caller":"traceutil/trace.go:171","msg":"trace[345939443] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"135.959168ms","start":"2026-05-09T22:36:17.468984Z","end":"2026-05-09T22:36:17.604943Z","steps":["trace[345939443] 'process raft request' (duration: 135.534369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:28.030022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.143156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:28.030122Z","caller":"traceutil/trace.go:171","msg":"trace[1081436274] linearizableReadLoop","detail":"{readStateIndex:26482; appliedIndex:26481; }","duration":"225.618995ms","start":"2026-05-09T22:36:27.804487Z","end":"2026-05-09T22:36:28.030106Z","steps":["trace[1081436274] 'read index received' (duration: 15.290076ms)","trace[1081436274] 'applied index is now lower than readState.Index' (duration: 210.327769ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:28.030348Z","caller":"traceutil/trace.go:171","msg":"trace[1021628074] transaction","detail":"{read_only:false; response_revision:25647; number_of_response:1; }","duration":"373.618935ms","start":"2026-05-09T22:36:27.656713Z","end":"2026-05-09T22:36:28.030332Z","steps":["trace[1021628074] 'process raft request' (duration: 163.082521ms)","trace[1021628074] 'compare' (duration: 210.011744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:28.030418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:27.6567Z","time spent":"373.686697ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:36:28.030679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.196908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-05-09T22:36:28.030718Z","caller":"traceutil/trace.go:171","msg":"trace[1577188692] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25647; }","duration":"226.277669ms","start":"2026-05-09T22:36:27.804432Z","end":"2026-05-09T22:36:28.03071Z","steps":["trace[1577188692] 'agreement among raft nodes before linearized reading' (duration: 226.152677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:28.35336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.180738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18419"} {"level":"info","ts":"2026-05-09T22:36:28.353456Z","caller":"traceutil/trace.go:171","msg":"trace[263568871] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25647; }","duration":"222.31657ms","start":"2026-05-09T22:36:28.131118Z","end":"2026-05-09T22:36:28.353434Z","steps":["trace[263568871] 'range keys from in-memory index tree' (duration: 222.042686ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:28.353472Z","caller":"traceutil/trace.go:171","msg":"trace[1608623425] linearizableReadLoop","detail":"{readStateIndex:26483; appliedIndex:26482; }","duration":"171.633414ms","start":"2026-05-09T22:36:28.18181Z","end":"2026-05-09T22:36:28.353444Z","steps":["trace[1608623425] 'read index received' (duration: 123.454026ms)","trace[1608623425] 'applied index is now lower than readState.Index' (duration: 48.178368ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:28.35361Z","caller":"traceutil/trace.go:171","msg":"trace[675051652] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"192.89176ms","start":"2026-05-09T22:36:28.160603Z","end":"2026-05-09T22:36:28.353494Z","steps":["trace[675051652] 'process raft request' (duration: 144.674971ms)","trace[675051652] 'compare' (duration: 47.727019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:28.353689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.866189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:28.353735Z","caller":"traceutil/trace.go:171","msg":"trace[103659902] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25648; }","duration":"171.974751ms","start":"2026-05-09T22:36:28.181746Z","end":"2026-05-09T22:36:28.353721Z","steps":["trace[103659902] 'agreement among raft nodes before linearized reading' (duration: 171.841028ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:28.774769Z","caller":"traceutil/trace.go:171","msg":"trace[1652323528] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"113.707632ms","start":"2026-05-09T22:36:28.661031Z","end":"2026-05-09T22:36:28.774739Z","steps":["trace[1652323528] 'process raft request' (duration: 113.518938ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:31.925109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22313} {"level":"info","ts":"2026-05-09T22:36:32.041085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22313,"took":"112.417045ms","hash":931708483,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":43360256,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-09T22:36:32.041141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":931708483,"revision":22313,"compact-revision":20036} {"level":"info","ts":"2026-05-09T22:36:33.863447Z","caller":"traceutil/trace.go:171","msg":"trace[1922665213] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"126.742704ms","start":"2026-05-09T22:36:33.736674Z","end":"2026-05-09T22:36:33.863417Z","steps":["trace[1922665213] 'process raft request' (duration: 126.53502ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.182168Z","caller":"traceutil/trace.go:171","msg":"trace[1509299851] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26530; }","duration":"332.934428ms","start":"2026-05-09T22:36:33.849203Z","end":"2026-05-09T22:36:34.182137Z","steps":["trace[1509299851] 'read index received' (duration: 14.0045ms)","trace[1509299851] 'applied index is now lower than readState.Index' (duration: 318.928518ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:34.182364Z","caller":"traceutil/trace.go:171","msg":"trace[13890365] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"445.346344ms","start":"2026-05-09T22:36:33.736993Z","end":"2026-05-09T22:36:34.182339Z","steps":["trace[13890365] 'process raft request' (duration: 350.475852ms)","trace[13890365] 'compare' (duration: 94.061596ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:34.182497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:33.736966Z","time spent":"445.435176ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:36:34.182542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.318097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1042"} {"level":"warn","ts":"2026-05-09T22:36:34.182623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.357878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:34.182662Z","caller":"traceutil/trace.go:171","msg":"trace[1066967765] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:25696; }","duration":"333.486462ms","start":"2026-05-09T22:36:33.849141Z","end":"2026-05-09T22:36:34.182627Z","steps":["trace[1066967765] 'agreement among raft nodes before linearized reading' (duration: 333.204495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:34.182722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:33.84911Z","time spent":"333.602583ms","remote":"127.0.0.1:57570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":1065,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" "} {"level":"warn","ts":"2026-05-09T22:36:34.182954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.619559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-09T22:36:34.182986Z","caller":"traceutil/trace.go:171","msg":"trace[1441161313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25696; }","duration":"109.693252ms","start":"2026-05-09T22:36:34.073286Z","end":"2026-05-09T22:36:34.18298Z","steps":["trace[1441161313] 'agreement among raft nodes before linearized reading' (duration: 109.582569ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.182995Z","caller":"traceutil/trace.go:171","msg":"trace[934858924] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"445.694872ms","start":"2026-05-09T22:36:33.737286Z","end":"2026-05-09T22:36:34.182981Z","steps":["trace[934858924] 'process raft request' (duration: 444.680681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:34.183114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:33.737276Z","time spent":"445.758363ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:36:34.183183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.277598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-b7ng2\" ","response":"range_response_count:1 size:3719"} {"level":"info","ts":"2026-05-09T22:36:34.18321Z","caller":"traceutil/trace.go:171","msg":"trace[19407971] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-b7ng2; range_end:; response_count:1; response_revision:25696; }","duration":"197.33646ms","start":"2026-05-09T22:36:33.985867Z","end":"2026-05-09T22:36:34.183204Z","steps":["trace[19407971] 'agreement among raft nodes before linearized reading' (duration: 197.233258ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.182677Z","caller":"traceutil/trace.go:171","msg":"trace[484570701] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25696; }","duration":"322.433059ms","start":"2026-05-09T22:36:33.860225Z","end":"2026-05-09T22:36:34.182658Z","steps":["trace[484570701] 'agreement among raft nodes before linearized reading' (duration: 322.341687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:34.183464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:33.860213Z","time spent":"323.228025ms","remote":"127.0.0.1:55284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:36:34.456134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.406566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T22:36:34.456266Z","caller":"traceutil/trace.go:171","msg":"trace[222968728] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"268.16013ms","start":"2026-05-09T22:36:34.188081Z","end":"2026-05-09T22:36:34.456241Z","steps":["trace[222968728] 'process raft request' (duration: 130.513319ms)","trace[222968728] 'compare' (duration: 137.056619ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:34.464415Z","caller":"traceutil/trace.go:171","msg":"trace[1714164865] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"272.985756ms","start":"2026-05-09T22:36:34.191396Z","end":"2026-05-09T22:36:34.464382Z","steps":["trace[1714164865] 'process raft request' (duration: 272.773781ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.691344Z","caller":"traceutil/trace.go:171","msg":"trace[2055239454] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"220.705328ms","start":"2026-05-09T22:36:34.470608Z","end":"2026-05-09T22:36:34.691313Z","steps":["trace[2055239454] 'process raft request' (duration: 220.399942ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.691917Z","caller":"traceutil/trace.go:171","msg":"trace[1835096819] linearizableReadLoop","detail":"{readStateIndex:26539; appliedIndex:26536; }","duration":"212.737578ms","start":"2026-05-09T22:36:34.479167Z","end":"2026-05-09T22:36:34.691904Z","steps":["trace[1835096819] 'read index received' (duration: 211.82053ms)","trace[1835096819] 'applied index is now lower than readState.Index' (duration: 916.048µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:34.692099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.901651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:34.692146Z","caller":"traceutil/trace.go:171","msg":"trace[1743480419] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25702; }","duration":"213.019973ms","start":"2026-05-09T22:36:34.479111Z","end":"2026-05-09T22:36:34.692131Z","steps":["trace[1743480419] 'agreement among raft nodes before linearized reading' (duration: 212.923271ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.692041Z","caller":"traceutil/trace.go:171","msg":"trace[213018550] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"221.193968ms","start":"2026-05-09T22:36:34.470825Z","end":"2026-05-09T22:36:34.692019Z","steps":["trace[213018550] 'process raft request' (duration: 220.830871ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.691825Z","caller":"traceutil/trace.go:171","msg":"trace[126744358] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"220.962233ms","start":"2026-05-09T22:36:34.470839Z","end":"2026-05-09T22:36:34.691801Z","steps":["trace[126744358] 'process raft request' (duration: 220.406922ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.975547Z","caller":"traceutil/trace.go:171","msg":"trace[1315923938] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"276.444416ms","start":"2026-05-09T22:36:34.698888Z","end":"2026-05-09T22:36:34.975333Z","steps":["trace[1315923938] 'process raft request' (duration: 213.418282ms)","trace[1315923938] 'compare' (duration: 62.85872ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:34.989295Z","caller":"traceutil/trace.go:171","msg":"trace[2024566826] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"191.871689ms","start":"2026-05-09T22:36:34.797394Z","end":"2026-05-09T22:36:34.989266Z","steps":["trace[2024566826] 'process raft request' (duration: 191.528312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:34.989581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.004279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:34.989629Z","caller":"traceutil/trace.go:171","msg":"trace[1779158895] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25704; }","duration":"143.120411ms","start":"2026-05-09T22:36:34.846498Z","end":"2026-05-09T22:36:34.989618Z","steps":["trace[1779158895] 'agreement among raft nodes before linearized reading' (duration: 142.970968ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:34.989857Z","caller":"traceutil/trace.go:171","msg":"trace[691181244] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26539; }","duration":"142.6187ms","start":"2026-05-09T22:36:34.846545Z","end":"2026-05-09T22:36:34.989164Z","steps":["trace[691181244] 'read index received' (duration: 65.888591ms)","trace[691181244] 'applied index is now lower than readState.Index' (duration: 76.728739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T22:36:35.338042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.697899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T22:36:35.338118Z","caller":"traceutil/trace.go:171","msg":"trace[257143297] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:25704; }","duration":"244.832242ms","start":"2026-05-09T22:36:35.093266Z","end":"2026-05-09T22:36:35.338098Z","steps":["trace[257143297] 'range keys from in-memory index tree' (duration: 244.511115ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:35.338104Z","caller":"traceutil/trace.go:171","msg":"trace[1503385221] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"166.083372ms","start":"2026-05-09T22:36:35.171977Z","end":"2026-05-09T22:36:35.338061Z","steps":["trace[1503385221] 'process raft request' (duration: 161.406178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:35.338111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.764116ms","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-05-09T22:36:35.338322Z","caller":"traceutil/trace.go:171","msg":"trace[802716682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25704; }","duration":"176.038642ms","start":"2026-05-09T22:36:35.162256Z","end":"2026-05-09T22:36:35.338294Z","steps":["trace[802716682] 'range keys from in-memory index tree' (duration: 175.555482ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:35.567749Z","caller":"traceutil/trace.go:171","msg":"trace[1460583063] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"394.824401ms","start":"2026-05-09T22:36:35.172899Z","end":"2026-05-09T22:36:35.567723Z","steps":["trace[1460583063] 'process raft request' (duration: 394.608017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:35.567918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:35.172856Z","time spent":"394.970144ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:36:35.568032Z","caller":"traceutil/trace.go:171","msg":"trace[349435616] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"388.76879ms","start":"2026-05-09T22:36:35.179235Z","end":"2026-05-09T22:36:35.568004Z","steps":["trace[349435616] 'process raft request' (duration: 388.439793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:35.568185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:35.179215Z","time spent":"388.883492ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:36:35.568624Z","caller":"traceutil/trace.go:171","msg":"trace[1660504458] linearizableReadLoop","detail":"{readStateIndex:26546; appliedIndex:26541; }","duration":"354.782928ms","start":"2026-05-09T22:36:35.213811Z","end":"2026-05-09T22:36:35.568594Z","steps":["trace[1660504458] 'read index received' (duration: 119.553388ms)","trace[1660504458] 'applied index is now lower than readState.Index' (duration: 235.2283ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:35.568693Z","caller":"traceutil/trace.go:171","msg":"trace[568980567] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"388.856931ms","start":"2026-05-09T22:36:35.179805Z","end":"2026-05-09T22:36:35.568662Z","steps":["trace[568980567] 'process raft request' (duration: 388.426142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:35.568783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:35.17979Z","time spent":"388.950023ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T22:36:35.569373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.552853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:35.569505Z","caller":"traceutil/trace.go:171","msg":"trace[1057566492] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:25709; }","duration":"355.735247ms","start":"2026-05-09T22:36:35.213747Z","end":"2026-05-09T22:36:35.569482Z","steps":["trace[1057566492] 'agreement among raft nodes before linearized reading' (duration: 354.93481ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:35.568806Z","caller":"traceutil/trace.go:171","msg":"trace[1899836469] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"377.237808ms","start":"2026-05-09T22:36:35.191466Z","end":"2026-05-09T22:36:35.568704Z","steps":["trace[1899836469] 'process raft request' (duration: 377.038164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:35.569598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:35.213719Z","time spent":"355.859359ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-09T22:36:35.570504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T22:36:35.191451Z","time spent":"378.889381ms","remote":"127.0.0.1:57634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T22:36:35.571635Z","caller":"traceutil/trace.go:171","msg":"trace[351214847] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"229.042094ms","start":"2026-05-09T22:36:35.342569Z","end":"2026-05-09T22:36:35.571611Z","steps":["trace[351214847] 'process raft request' (duration: 228.85852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:35.571931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.224144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:36:35.572004Z","caller":"traceutil/trace.go:171","msg":"trace[151243920] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:25711; }","duration":"143.338746ms","start":"2026-05-09T22:36:35.428634Z","end":"2026-05-09T22:36:35.571972Z","steps":["trace[151243920] 'agreement among raft nodes before linearized reading' (duration: 143.192983ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:59.679994Z","caller":"traceutil/trace.go:171","msg":"trace[1316509864] linearizableReadLoop","detail":"{readStateIndex:26868; appliedIndex:26867; }","duration":"237.217148ms","start":"2026-05-09T22:36:59.442749Z","end":"2026-05-09T22:36:59.679966Z","steps":["trace[1316509864] 'read index received' (duration: 237.188497ms)","trace[1316509864] 'applied index is now lower than readState.Index' (duration: 27.221µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:59.680058Z","caller":"traceutil/trace.go:171","msg":"trace[960409197] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"256.112917ms","start":"2026-05-09T22:36:59.423915Z","end":"2026-05-09T22:36:59.680028Z","steps":["trace[960409197] 'process raft request' (duration: 255.858862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:59.680163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.393352ms","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-05-09T22:36:59.680212Z","caller":"traceutil/trace.go:171","msg":"trace[1672035305] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26024; }","duration":"237.494114ms","start":"2026-05-09T22:36:59.442705Z","end":"2026-05-09T22:36:59.680199Z","steps":["trace[1672035305] 'agreement among raft nodes before linearized reading' (duration: 237.385821ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:59.859452Z","caller":"traceutil/trace.go:171","msg":"trace[1268674962] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"189.546242ms","start":"2026-05-09T22:36:59.669886Z","end":"2026-05-09T22:36:59.859432Z","steps":["trace[1268674962] 'process raft request' (duration: 167.278025ms)","trace[1268674962] 'compare' (duration: 22.112264ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:59.859789Z","caller":"traceutil/trace.go:171","msg":"trace[1958914423] linearizableReadLoop","detail":"{readStateIndex:26872; appliedIndex:26868; }","duration":"179.672155ms","start":"2026-05-09T22:36:59.680094Z","end":"2026-05-09T22:36:59.859767Z","steps":["trace[1958914423] 'read index received' (duration: 157.052661ms)","trace[1958914423] 'applied index is now lower than readState.Index' (duration: 22.618354ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T22:36:59.859832Z","caller":"traceutil/trace.go:171","msg":"trace[1664861213] transaction","detail":"{read_only:false; response_revision:26027; number_of_response:1; }","duration":"181.708924ms","start":"2026-05-09T22:36:59.678084Z","end":"2026-05-09T22:36:59.859793Z","steps":["trace[1664861213] 'process raft request' (duration: 181.528101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:59.859958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.81079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T22:36:59.860001Z","caller":"traceutil/trace.go:171","msg":"trace[1198529263] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:26028; }","duration":"216.907902ms","start":"2026-05-09T22:36:59.643085Z","end":"2026-05-09T22:36:59.859993Z","steps":["trace[1198529263] 'agreement among raft nodes before linearized reading' (duration: 216.81599ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:59.860092Z","caller":"traceutil/trace.go:171","msg":"trace[2021021468] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"188.90493ms","start":"2026-05-09T22:36:59.671173Z","end":"2026-05-09T22:36:59.860078Z","steps":["trace[2021021468] 'process raft request' (duration: 188.224175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:59.859884Z","caller":"traceutil/trace.go:171","msg":"trace[1092123135] transaction","detail":"{read_only:false; response_revision:26028; number_of_response:1; }","duration":"180.567412ms","start":"2026-05-09T22:36:59.679307Z","end":"2026-05-09T22:36:59.859874Z","steps":["trace[1092123135] 'process raft request' (duration: 180.408319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:59.860235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.915395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:59.860271Z","caller":"traceutil/trace.go:171","msg":"trace[2058647667] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:26028; }","duration":"286.012328ms","start":"2026-05-09T22:36:59.574247Z","end":"2026-05-09T22:36:59.860259Z","steps":["trace[2058647667] 'agreement among raft nodes before linearized reading' (duration: 285.935346ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:36:59.887364Z","caller":"traceutil/trace.go:171","msg":"trace[662906697] transaction","detail":"{read_only:false; response_revision:26029; number_of_response:1; }","duration":"207.030563ms","start":"2026-05-09T22:36:59.680302Z","end":"2026-05-09T22:36:59.887333Z","steps":["trace[662906697] 'process raft request' (duration: 206.737387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:59.887472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.694126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T22:36:59.887591Z","caller":"traceutil/trace.go:171","msg":"trace[45602161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26029; }","duration":"187.883119ms","start":"2026-05-09T22:36:59.699683Z","end":"2026-05-09T22:36:59.887566Z","steps":["trace[45602161] 'agreement among raft nodes before linearized reading' (duration: 187.588134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T22:36:59.887506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.688156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T22:36:59.887761Z","caller":"traceutil/trace.go:171","msg":"trace[1513579576] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:26029; }","duration":"187.965841ms","start":"2026-05-09T22:36:59.699762Z","end":"2026-05-09T22:36:59.887728Z","steps":["trace[1513579576] 'agreement among raft nodes before linearized reading' (duration: 187.637184ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T22:37:40.392695Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a5c.wal"} {"level":"info","ts":"2026-05-09T22:41:31.931598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25676} {"level":"info","ts":"2026-05-09T22:41:32.087244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25676,"took":"151.219804ms","hash":2504316699,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":35659776,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-09T22:41:32.087322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2504316699,"revision":25676,"compact-revision":22313} {"level":"info","ts":"2026-05-09T22:42:41.98362Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T22:42:41.988948Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T22:42:41.989036Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T22:46:31.938597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28563} {"level":"info","ts":"2026-05-09T22:46:32.065093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28563,"took":"123.726931ms","hash":2019554578,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":30535680,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T22:46:32.065141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2019554578,"revision":28563,"compact-revision":25676} {"level":"info","ts":"2026-05-09T22:51:31.944888Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30787} {"level":"info","ts":"2026-05-09T22:51:32.061817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30787,"took":"112.456285ms","hash":948786884,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":31084544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T22:51:32.061875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":948786884,"revision":30787,"compact-revision":28563} {"level":"info","ts":"2026-05-09T22:56:31.952133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33055} {"level":"info","ts":"2026-05-09T22:56:32.09774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33055,"took":"141.925211ms","hash":3276921806,"current-db-size-bytes":59138048,"current-db-size":"59 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T22:56:32.097805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3276921806,"revision":33055,"compact-revision":30787} {"level":"info","ts":"2026-05-09T23:01:31.960353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35352} {"level":"info","ts":"2026-05-09T23:01:32.106141Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35352,"took":"141.796627ms","hash":3788970338,"current-db-size-bytes":60841984,"current-db-size":"61 MB","current-db-size-in-use-bytes":31596544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T23:01:32.106208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3788970338,"revision":35352,"compact-revision":33055} {"level":"info","ts":"2026-05-09T23:04:02.89992Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T23:04:02.904856Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-09T23:04:02.90495Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}