{"level":"warn","ts":"2026-04-14T06:27:49.611193Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T06:27:49.611313Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-14T06:27:49.611409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T06:27:49.611429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-14T06:27:49.611451Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T06:27:49.612257Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-04-14T06:27:49.612352Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-14T06:27:49.616576Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.954176ms"} {"level":"info","ts":"2026-04-14T06:27:49.624108Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-04-14T06:27:49.624206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T06:27:49.624232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-04-14T06:27:49.624246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T06:27:49.62426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-04-14T06:27:49.624319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-04-14T06:27:49.637618Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T06:27:49.639095Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T06:27:49.640483Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-14T06:27:49.644595Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T06:27:49.644882Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T06:27:49.644992Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T06:27:49.645057Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T06:27:49.645067Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-14T06:27:49.646335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-04-14T06:27:49.648696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-04-14T06:27:49.651264Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-14T06:27:49.651349Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-14T06:27:49.651407Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-04-14T06:27:49.651483Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T06:27:49.651532Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T06:27:50.224962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T06:27:50.225031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T06:27:50.225083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-04-14T06:27:50.225105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-04-14T06:27:50.225117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-14T06:27:50.225134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-04-14T06:27:50.225148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-04-14T06:27:50.226473Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:27:50.22704Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:27:50.227052Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T06:27:50.22726Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T06:27:50.229339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-04-14T06:27:50.23009Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T06:27:50.234446Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T06:27:50.234545Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T06:27:50.234687Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:27:50.234801Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:27:50.234831Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T06:28:11.764805Z","caller":"traceutil/trace.go:171","msg":"trace[1722468800] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"128.016965ms","start":"2026-04-14T06:28:11.63676Z","end":"2026-04-14T06:28:11.764777Z","steps":["trace[1722468800] 'process raft request' (duration: 127.79003ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:11.891321Z","caller":"traceutil/trace.go:171","msg":"trace[1571885490] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"122.829865ms","start":"2026-04-14T06:28:11.768464Z","end":"2026-04-14T06:28:11.891294Z","steps":["trace[1571885490] 'process raft request' (duration: 119.72535ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:20.412493Z","caller":"traceutil/trace.go:171","msg":"trace[144880812] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"124.334508ms","start":"2026-04-14T06:28:20.288133Z","end":"2026-04-14T06:28:20.412468Z","steps":["trace[144880812] 'read index received' (duration: 112.367476ms)","trace[144880812] 'applied index is now lower than readState.Index' (duration: 11.965832ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:28:20.412628Z","caller":"traceutil/trace.go:171","msg":"trace[283959368] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"135.462522ms","start":"2026-04-14T06:28:20.277125Z","end":"2026-04-14T06:28:20.412588Z","steps":["trace[283959368] 'process raft request' (duration: 123.422239ms)","trace[283959368] 'compare' (duration: 11.787888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:28:20.412717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.556722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-14T06:28:20.412808Z","caller":"traceutil/trace.go:171","msg":"trace[1990765457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"124.691224ms","start":"2026-04-14T06:28:20.288102Z","end":"2026-04-14T06:28:20.412793Z","steps":["trace[1990765457] 'agreement among raft nodes before linearized reading' (duration: 124.47865ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:26.669138Z","caller":"traceutil/trace.go:171","msg":"trace[426637104] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"107.569644ms","start":"2026-04-14T06:28:26.561548Z","end":"2026-04-14T06:28:26.669118Z","steps":["trace[426637104] 'process raft request' (duration: 107.40305ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:27.190226Z","caller":"traceutil/trace.go:171","msg":"trace[101297051] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"102.485006ms","start":"2026-04-14T06:28:27.087679Z","end":"2026-04-14T06:28:27.190164Z","steps":["trace[101297051] 'process raft request' (duration: 102.264411ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:55.853834Z","caller":"traceutil/trace.go:171","msg":"trace[532930502] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"213.875093ms","start":"2026-04-14T06:28:55.639936Z","end":"2026-04-14T06:28:55.853811Z","steps":["trace[532930502] 'read index received' (duration: 213.75788ms)","trace[532930502] 'applied index is now lower than readState.Index' (duration: 115.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:28:55.853907Z","caller":"traceutil/trace.go:171","msg":"trace[1112770709] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"214.345173ms","start":"2026-04-14T06:28:55.639514Z","end":"2026-04-14T06:28:55.853859Z","steps":["trace[1112770709] 'process raft request' (duration: 214.160499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:28:55.853973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.026996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:28:55.854Z","caller":"traceutil/trace.go:171","msg":"trace[1777560209] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:0; response_revision:920; }","duration":"214.105827ms","start":"2026-04-14T06:28:55.639888Z","end":"2026-04-14T06:28:55.853994Z","steps":["trace[1777560209] 'agreement among raft nodes before linearized reading' (duration: 214.036936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:28:55.854079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.002556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/rook-ceph/\" range_end:\"/registry/secrets/rook-ceph0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:28:55.854164Z","caller":"traceutil/trace.go:171","msg":"trace[389650666] range","detail":"{range_begin:/registry/secrets/rook-ceph/; range_end:/registry/secrets/rook-ceph0; response_count:0; response_revision:920; }","duration":"167.15001ms","start":"2026-04-14T06:28:55.686993Z","end":"2026-04-14T06:28:55.854143Z","steps":["trace[389650666] 'agreement among raft nodes before linearized reading' (duration: 166.990016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:28:55.854164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.30614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:28:55.854273Z","caller":"traceutil/trace.go:171","msg":"trace[1337150084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"140.450183ms","start":"2026-04-14T06:28:55.713804Z","end":"2026-04-14T06:28:55.854254Z","steps":["trace[1337150084] 'agreement among raft nodes before linearized reading' (duration: 140.281549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:28:55.854246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.931466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:28:55.854422Z","caller":"traceutil/trace.go:171","msg":"trace[957417498] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:0; response_revision:920; }","duration":"135.193841ms","start":"2026-04-14T06:28:55.719199Z","end":"2026-04-14T06:28:55.854392Z","steps":["trace[957417498] 'agreement among raft nodes before linearized reading' (duration: 134.872014ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:57.073516Z","caller":"traceutil/trace.go:171","msg":"trace[313869497] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"107.374341ms","start":"2026-04-14T06:28:56.966119Z","end":"2026-04-14T06:28:57.073493Z","steps":["trace[313869497] 'process raft request' (duration: 92.153377ms)","trace[313869497] 'compare' (duration: 14.56459ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:28:57.073791Z","caller":"traceutil/trace.go:171","msg":"trace[1040077710] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"107.34273ms","start":"2026-04-14T06:28:56.966438Z","end":"2026-04-14T06:28:57.073781Z","steps":["trace[1040077710] 'process raft request' (duration: 106.516452ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:28:57.432933Z","caller":"traceutil/trace.go:171","msg":"trace[1286865839] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"103.229883ms","start":"2026-04-14T06:28:57.329634Z","end":"2026-04-14T06:28:57.432864Z","steps":["trace[1286865839] 'compare' (duration: 93.522537ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:05.511566Z","caller":"traceutil/trace.go:171","msg":"trace[966037211] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"103.251683ms","start":"2026-04-14T06:29:05.408292Z","end":"2026-04-14T06:29:05.511544Z","steps":["trace[966037211] 'process raft request' (duration: 103.11535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.02784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.303719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:29:06.028692Z","caller":"traceutil/trace.go:171","msg":"trace[1874709135] linearizableReadLoop","detail":"{readStateIndex:1499; appliedIndex:1496; }","duration":"603.712312ms","start":"2026-04-14T06:29:05.424946Z","end":"2026-04-14T06:29:06.028659Z","steps":["trace[1874709135] 'read index received' (duration: 86.466636ms)","trace[1874709135] 'applied index is now lower than readState.Index' (duration: 517.243956ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:29:06.028687Z","caller":"traceutil/trace.go:171","msg":"trace[779653397] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"619.564619ms","start":"2026-04-14T06:29:05.409057Z","end":"2026-04-14T06:29:06.028621Z","steps":["trace[779653397] 'process raft request' (duration: 204.733168ms)","trace[779653397] 'compare' (duration: 413.112235ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:29:06.028826Z","caller":"traceutil/trace.go:171","msg":"trace[1087969858] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"614.881859ms","start":"2026-04-14T06:29:05.413932Z","end":"2026-04-14T06:29:06.028813Z","steps":["trace[1087969858] 'process raft request' (duration: 614.580852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.028921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:05.413909Z","time spent":"614.953261ms","remote":"127.0.0.1:54812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9429,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:29:06.028941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:05.409028Z","time spent":"619.775724ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9879,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:29:06.029307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.361327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:61818"} {"level":"info","ts":"2026-04-14T06:29:06.029388Z","caller":"traceutil/trace.go:171","msg":"trace[1010099057] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1459; }","duration":"604.490599ms","start":"2026-04-14T06:29:05.424874Z","end":"2026-04-14T06:29:06.029364Z","steps":["trace[1010099057] 'agreement among raft nodes before linearized reading' (duration: 604.045259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.029473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:05.424848Z","time spent":"604.616031ms","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":3,"response size":61841,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-04-14T06:29:06.373926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.667095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:29:06.374109Z","caller":"traceutil/trace.go:171","msg":"trace[1081365375] linearizableReadLoop","detail":"{readStateIndex:1500; appliedIndex:1499; }","duration":"345.329505ms","start":"2026-04-14T06:29:06.028758Z","end":"2026-04-14T06:29:06.374087Z","steps":["trace[1081365375] 'read index received' (duration: 223.362334ms)","trace[1081365375] 'applied index is now lower than readState.Index' (duration: 121.965571ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:29:06.374165Z","caller":"traceutil/trace.go:171","msg":"trace[1683480631] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"484.612882ms","start":"2026-04-14T06:29:05.889502Z","end":"2026-04-14T06:29:06.374115Z","steps":["trace[1683480631] 'process raft request' (duration: 362.59493ms)","trace[1683480631] 'compare' (duration: 121.433359ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:29:06.374281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.268751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:29:06.374365Z","caller":"traceutil/trace.go:171","msg":"trace[1566057576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1460; }","duration":"658.370223ms","start":"2026-04-14T06:29:05.715975Z","end":"2026-04-14T06:29:06.374345Z","steps":["trace[1566057576] 'agreement among raft nodes before linearized reading' (duration: 658.184539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.374419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:05.889483Z","time spent":"484.819407ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:29:06.374459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.524345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-4lwcq\" ","response":"range_response_count:1 size:7666"} {"level":"warn","ts":"2026-04-14T06:29:06.374374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.895496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:29:06.374569Z","caller":"traceutil/trace.go:171","msg":"trace[1100532760] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-cf56b6468-4lwcq; range_end:; response_count:1; response_revision:1460; }","duration":"624.704398ms","start":"2026-04-14T06:29:05.749838Z","end":"2026-04-14T06:29:06.374543Z","steps":["trace[1100532760] 'agreement among raft nodes before linearized reading' (duration: 624.29442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.374641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:05.749812Z","time spent":"624.810661ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":7689,"request content":"key:\"/registry/pods/openstack/memcached-memcached-cf56b6468-4lwcq\" "} {"level":"info","ts":"2026-04-14T06:29:06.374616Z","caller":"traceutil/trace.go:171","msg":"trace[1242226163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1460; }","duration":"790.153872ms","start":"2026-04-14T06:29:05.584417Z","end":"2026-04-14T06:29:06.37457Z","steps":["trace[1242226163] 'agreement among raft nodes before linearized reading' (duration: 789.760424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.374463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:05.715862Z","time spent":"658.579727ms","remote":"127.0.0.1:54310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:29:06.374779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:05.584391Z","time spent":"790.337456ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T06:29:06.430079Z","caller":"traceutil/trace.go:171","msg":"trace[1913523923] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"395.93144ms","start":"2026-04-14T06:29:06.034099Z","end":"2026-04-14T06:29:06.43003Z","steps":["trace[1913523923] 'process raft request' (duration: 395.554071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.430144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.304571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:3 size:61818"} {"level":"info","ts":"2026-04-14T06:29:06.430172Z","caller":"traceutil/trace.go:171","msg":"trace[1011417144] range","detail":"{range_begin:/registry/serviceaccounts/openstack/openvswitch-server; range_end:; response_count:1; response_revision:1462; }","duration":"100.035776ms","start":"2026-04-14T06:29:06.330113Z","end":"2026-04-14T06:29:06.430149Z","steps":["trace[1011417144] 'agreement among raft nodes before linearized reading' (duration: 99.837091ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:06.430254Z","caller":"traceutil/trace.go:171","msg":"trace[230775401] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:3; response_revision:1462; }","duration":"334.461824ms","start":"2026-04-14T06:29:06.095773Z","end":"2026-04-14T06:29:06.430235Z","steps":["trace[230775401] 'agreement among raft nodes before linearized reading' (duration: 334.143498ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:06.430098Z","caller":"traceutil/trace.go:171","msg":"trace[322126498] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"389.76244ms","start":"2026-04-14T06:29:06.0403Z","end":"2026-04-14T06:29:06.430062Z","steps":["trace[322126498] 'process raft request' (duration: 389.548515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.4303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:06.09575Z","time spent":"334.539926ms","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":3,"response size":61841,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-04-14T06:29:06.430357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.788637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T06:29:06.430415Z","caller":"traceutil/trace.go:171","msg":"trace[505875525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1462; }","duration":"121.90905ms","start":"2026-04-14T06:29:06.308493Z","end":"2026-04-14T06:29:06.430402Z","steps":["trace[505875525] 'agreement among raft nodes before linearized reading' (duration: 121.690075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.43044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:06.034075Z","time spent":"396.115474ms","remote":"127.0.0.1:54424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":684,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:29:06.430404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:06.040286Z","time spent":"390.041645ms","remote":"127.0.0.1:54812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:29:06.4305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.687819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T06:29:06.430628Z","caller":"traceutil/trace.go:171","msg":"trace[83557182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1462; }","duration":"302.899273ms","start":"2026-04-14T06:29:06.127702Z","end":"2026-04-14T06:29:06.430601Z","steps":["trace[83557182] 'agreement among raft nodes before linearized reading' (duration: 302.471774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:06.430687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:29:06.127662Z","time spent":"303.015316ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:29:06.431445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.498266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-14T06:29:06.431623Z","caller":"traceutil/trace.go:171","msg":"trace[149488186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1462; }","duration":"222.73219ms","start":"2026-04-14T06:29:06.208862Z","end":"2026-04-14T06:29:06.431594Z","steps":["trace[149488186] 'agreement among raft nodes before linearized reading' (duration: 221.275609ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:12.935596Z","caller":"traceutil/trace.go:171","msg":"trace[2034393047] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"106.863239ms","start":"2026-04-14T06:29:12.828708Z","end":"2026-04-14T06:29:12.935571Z","steps":["trace[2034393047] 'process raft request' (duration: 101.458195ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:12.960339Z","caller":"traceutil/trace.go:171","msg":"trace[182570120] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"124.524536ms","start":"2026-04-14T06:29:12.835789Z","end":"2026-04-14T06:29:12.960314Z","steps":["trace[182570120] 'process raft request' (duration: 124.415354ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:42.943802Z","caller":"traceutil/trace.go:171","msg":"trace[1906831254] linearizableReadLoop","detail":"{readStateIndex:1876; appliedIndex:1875; }","duration":"196.651607ms","start":"2026-04-14T06:29:42.747125Z","end":"2026-04-14T06:29:42.943777Z","steps":["trace[1906831254] 'read index received' (duration: 196.485944ms)","trace[1906831254] 'applied index is now lower than readState.Index' (duration: 164.503µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:29:42.943877Z","caller":"traceutil/trace.go:171","msg":"trace[1266063206] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"220.020184ms","start":"2026-04-14T06:29:42.723819Z","end":"2026-04-14T06:29:42.943839Z","steps":["trace[1266063206] 'process raft request' (duration: 219.780209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:42.944059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.913963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T06:29:42.944105Z","caller":"traceutil/trace.go:171","msg":"trace[227256647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1828; }","duration":"197.011825ms","start":"2026-04-14T06:29:42.747081Z","end":"2026-04-14T06:29:42.944093Z","steps":["trace[227256647] 'agreement among raft nodes before linearized reading' (duration: 196.854892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:42.944179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.614696ms","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-04-14T06:29:42.944273Z","caller":"traceutil/trace.go:171","msg":"trace[667067384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1828; }","duration":"196.746709ms","start":"2026-04-14T06:29:42.747505Z","end":"2026-04-14T06:29:42.944252Z","steps":["trace[667067384] 'agreement among raft nodes before linearized reading' (duration: 196.511294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:42.944184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.723728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:29:42.944399Z","caller":"traceutil/trace.go:171","msg":"trace[2123910514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1828; }","duration":"196.931942ms","start":"2026-04-14T06:29:42.747426Z","end":"2026-04-14T06:29:42.944358Z","steps":["trace[2123910514] 'agreement among raft nodes before linearized reading' (duration: 196.568705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:43.104846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.931324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:29:43.104948Z","caller":"traceutil/trace.go:171","msg":"trace[139549296] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"156.882383ms","start":"2026-04-14T06:29:42.94805Z","end":"2026-04-14T06:29:43.104933Z","steps":["trace[139549296] 'process raft request' (duration: 55.774035ms)","trace[139549296] 'compare' (duration: 100.823792ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:29:43.120822Z","caller":"traceutil/trace.go:171","msg":"trace[1190954121] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"172.459654ms","start":"2026-04-14T06:29:42.948346Z","end":"2026-04-14T06:29:43.120806Z","steps":["trace[1190954121] 'process raft request' (duration: 172.339271ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:43.121068Z","caller":"traceutil/trace.go:171","msg":"trace[1702919424] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"171.620875ms","start":"2026-04-14T06:29:42.949411Z","end":"2026-04-14T06:29:43.121032Z","steps":["trace[1702919424] 'process raft request' (duration: 171.36892ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:45.319792Z","caller":"traceutil/trace.go:171","msg":"trace[1119992037] linearizableReadLoop","detail":"{readStateIndex:1911; appliedIndex:1910; }","duration":"195.014272ms","start":"2026-04-14T06:29:45.124753Z","end":"2026-04-14T06:29:45.319767Z","steps":["trace[1119992037] 'read index received' (duration: 194.728946ms)","trace[1119992037] 'applied index is now lower than readState.Index' (duration: 284.206µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:29:45.319906Z","caller":"traceutil/trace.go:171","msg":"trace[1713046484] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"204.973443ms","start":"2026-04-14T06:29:45.114865Z","end":"2026-04-14T06:29:45.319839Z","steps":["trace[1713046484] 'process raft request' (duration: 204.707648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:45.320077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.307018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:29:45.320137Z","caller":"traceutil/trace.go:171","msg":"trace[1165817490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1862; }","duration":"195.42681ms","start":"2026-04-14T06:29:45.124695Z","end":"2026-04-14T06:29:45.320121Z","steps":["trace[1165817490] 'agreement among raft nodes before linearized reading' (duration: 195.210626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:45.320167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.92465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T06:29:45.320245Z","caller":"traceutil/trace.go:171","msg":"trace[871365661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"195.051282ms","start":"2026-04-14T06:29:45.125176Z","end":"2026-04-14T06:29:45.320227Z","steps":["trace[871365661] 'agreement among raft nodes before linearized reading' (duration: 194.810847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:29:45.320498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.859711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-14T06:29:45.320618Z","caller":"traceutil/trace.go:171","msg":"trace[1084790737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1862; }","duration":"140.049635ms","start":"2026-04-14T06:29:45.180533Z","end":"2026-04-14T06:29:45.320583Z","steps":["trace[1084790737] 'agreement among raft nodes before linearized reading' (duration: 139.606616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:08.905666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.079837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:30:08.905826Z","caller":"traceutil/trace.go:171","msg":"trace[865211740] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"117.787385ms","start":"2026-04-14T06:30:08.788024Z","end":"2026-04-14T06:30:08.905812Z","steps":["trace[865211740] 'process raft request' (duration: 117.737184ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:08.905934Z","caller":"traceutil/trace.go:171","msg":"trace[431407040] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"117.99735ms","start":"2026-04-14T06:30:08.787916Z","end":"2026-04-14T06:30:08.905913Z","steps":["trace[431407040] 'compare' (duration: 114.964344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.448569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.290801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:30:09.448868Z","caller":"traceutil/trace.go:171","msg":"trace[274980450] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"466.341177ms","start":"2026-04-14T06:30:08.982497Z","end":"2026-04-14T06:30:09.448838Z","steps":["trace[274980450] 'process raft request' (duration: 350.680248ms)","trace[274980450] 'compare' (duration: 114.206259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:09.448994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:08.982482Z","time spent":"466.461361ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:30:09.449147Z","caller":"traceutil/trace.go:171","msg":"trace[21361893] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"466.45449ms","start":"2026-04-14T06:30:08.982681Z","end":"2026-04-14T06:30:09.449136Z","steps":["trace[21361893] 'process raft request' (duration: 466.031101ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:09.449159Z","caller":"traceutil/trace.go:171","msg":"trace[1741416984] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"466.387339ms","start":"2026-04-14T06:30:08.982751Z","end":"2026-04-14T06:30:09.449138Z","steps":["trace[1741416984] 'process raft request' (duration: 466.266106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.449244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:08.982658Z","time spent":"466.530492ms","remote":"127.0.0.1:54424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":933,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:30:09.44934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:08.982739Z","time spent":"466.4629ms","remote":"127.0.0.1:54448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30482,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:30:09.464387Z","caller":"traceutil/trace.go:171","msg":"trace[1150619773] linearizableReadLoop","detail":"{readStateIndex:2701; appliedIndex:2697; }","duration":"392.967295ms","start":"2026-04-14T06:30:09.071399Z","end":"2026-04-14T06:30:09.464366Z","steps":["trace[1150619773] 'read index received' (duration: 261.794732ms)","trace[1150619773] 'applied index is now lower than readState.Index' (duration: 131.171653ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:09.4645Z","caller":"traceutil/trace.go:171","msg":"trace[740116692] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"209.664785ms","start":"2026-04-14T06:30:09.254806Z","end":"2026-04-14T06:30:09.464471Z","steps":["trace[740116692] 'process raft request' (duration: 209.509442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.464599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.190069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-14T06:30:09.464499Z","caller":"traceutil/trace.go:171","msg":"trace[1599261193] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"430.438569ms","start":"2026-04-14T06:30:09.034023Z","end":"2026-04-14T06:30:09.464461Z","steps":["trace[1599261193] 'process raft request' (duration: 430.167653ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:09.464626Z","caller":"traceutil/trace.go:171","msg":"trace[1225841302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2611; }","duration":"393.269011ms","start":"2026-04-14T06:30:09.07135Z","end":"2026-04-14T06:30:09.464619Z","steps":["trace[1225841302] 'agreement among raft nodes before linearized reading' (duration: 393.119348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.464681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:09.071332Z","time spent":"393.315832ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T06:30:09.464748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.311255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-42148a17-2a48-4985-8657-0d5f16bc3029\" ","response":"range_response_count:1 size:3617"} {"level":"warn","ts":"2026-04-14T06:30:09.464771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.741884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58cdbb70-2c85-42f6-83ed-7031c52dee68\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-04-14T06:30:09.4648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:09.033999Z","time spent":"430.656323ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18075,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T06:30:09.464888Z","caller":"traceutil/trace.go:171","msg":"trace[1842270756] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58cdbb70-2c85-42f6-83ed-7031c52dee68; range_end:; response_count:1; response_revision:2611; }","duration":"249.899057ms","start":"2026-04-14T06:30:09.214972Z","end":"2026-04-14T06:30:09.464871Z","steps":["trace[1842270756] 'agreement among raft nodes before linearized reading' (duration: 249.670552ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:09.464829Z","caller":"traceutil/trace.go:171","msg":"trace[674761757] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-42148a17-2a48-4985-8657-0d5f16bc3029; range_end:; response_count:1; response_revision:2611; }","duration":"172.452357ms","start":"2026-04-14T06:30:09.292362Z","end":"2026-04-14T06:30:09.464815Z","steps":["trace[674761757] 'agreement among raft nodes before linearized reading' (duration: 172.184981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.464768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.425294ms","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-04-14T06:30:09.465101Z","caller":"traceutil/trace.go:171","msg":"trace[1489696044] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2611; }","duration":"219.867963ms","start":"2026-04-14T06:30:09.245203Z","end":"2026-04-14T06:30:09.465071Z","steps":["trace[1489696044] 'agreement among raft nodes before linearized reading' (duration: 219.363343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.465277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.685847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58cdbb70-2c85-42f6-83ed-7031c52dee68\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-14T06:30:09.465326Z","caller":"traceutil/trace.go:171","msg":"trace[542077467] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58cdbb70-2c85-42f6-83ed-7031c52dee68; range_end:; response_count:1; response_revision:2611; }","duration":"363.775599ms","start":"2026-04-14T06:30:09.101537Z","end":"2026-04-14T06:30:09.465313Z","steps":["trace[542077467] 'agreement among raft nodes before linearized reading' (duration: 363.000112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.465356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:09.101517Z","time spent":"363.83028ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58cdbb70-2c85-42f6-83ed-7031c52dee68\" "} {"level":"info","ts":"2026-04-14T06:30:09.966652Z","caller":"traceutil/trace.go:171","msg":"trace[1551396187] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2713; }","duration":"269.176761ms","start":"2026-04-14T06:30:09.697423Z","end":"2026-04-14T06:30:09.9666Z","steps":["trace[1551396187] 'read index received' (duration: 231.599815ms)","trace[1551396187] 'applied index is now lower than readState.Index' (duration: 37.574946ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:09.966833Z","caller":"traceutil/trace.go:171","msg":"trace[345948724] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"286.714346ms","start":"2026-04-14T06:30:09.680103Z","end":"2026-04-14T06:30:09.966817Z","steps":["trace[345948724] 'process raft request' (duration: 249.022209ms)","trace[345948724] 'compare' (duration: 36.824099ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:09.966928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.231725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:30:09.967043Z","caller":"traceutil/trace.go:171","msg":"trace[203131333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2623; }","duration":"251.383519ms","start":"2026-04-14T06:30:09.715643Z","end":"2026-04-14T06:30:09.967027Z","steps":["trace[203131333] 'agreement among raft nodes before linearized reading' (duration: 251.221945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.967112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.449249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8a5b76c0-5731-4ead-8618-78e9997fd3ff\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-14T06:30:09.967157Z","caller":"traceutil/trace.go:171","msg":"trace[364072930] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8a5b76c0-5731-4ead-8618-78e9997fd3ff; range_end:; response_count:1; response_revision:2623; }","duration":"187.574551ms","start":"2026-04-14T06:30:09.77957Z","end":"2026-04-14T06:30:09.967144Z","steps":["trace[364072930] 'agreement among raft nodes before linearized reading' (duration: 187.338976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.967268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.37364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/libvirt\" ","response":"range_response_count:1 size:517"} {"level":"info","ts":"2026-04-14T06:30:09.967364Z","caller":"traceutil/trace.go:171","msg":"trace[1395748408] range","detail":"{range_begin:/registry/serviceaccounts/openstack/libvirt; range_end:; response_count:1; response_revision:2623; }","duration":"245.518023ms","start":"2026-04-14T06:30:09.721823Z","end":"2026-04-14T06:30:09.967341Z","steps":["trace[1395748408] 'agreement among raft nodes before linearized reading' (duration: 245.161996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:09.969253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.825477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:19 size:505201"} {"level":"info","ts":"2026-04-14T06:30:09.969315Z","caller":"traceutil/trace.go:171","msg":"trace[1536552858] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:19; response_revision:2623; }","duration":"271.93064ms","start":"2026-04-14T06:30:09.69737Z","end":"2026-04-14T06:30:09.969301Z","steps":["trace[1536552858] 'agreement among raft nodes before linearized reading' (duration: 270.173602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:09.836374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.860377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:31:09.836456Z","caller":"traceutil/trace.go:171","msg":"trace[1167404832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3103; }","duration":"120.0028ms","start":"2026-04-14T06:31:09.716438Z","end":"2026-04-14T06:31:09.836441Z","steps":["trace[1167404832] 'range keys from in-memory index tree' (duration: 119.753514ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:09.845315Z","caller":"traceutil/trace.go:171","msg":"trace[1205364906] transaction","detail":"{read_only:false; response_revision:3104; number_of_response:1; }","duration":"119.02541ms","start":"2026-04-14T06:31:09.72626Z","end":"2026-04-14T06:31:09.845285Z","steps":["trace[1205364906] 'process raft request' (duration: 118.785405ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:09.859646Z","caller":"traceutil/trace.go:171","msg":"trace[1819268830] transaction","detail":"{read_only:false; response_revision:3105; number_of_response:1; }","duration":"100.8491ms","start":"2026-04-14T06:31:09.758753Z","end":"2026-04-14T06:31:09.859602Z","steps":["trace[1819268830] 'process raft request' (duration: 100.360349ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:10.03339Z","caller":"traceutil/trace.go:171","msg":"trace[2053261733] transaction","detail":"{read_only:false; response_revision:3108; number_of_response:1; }","duration":"111.63126ms","start":"2026-04-14T06:31:09.921724Z","end":"2026-04-14T06:31:10.033356Z","steps":["trace[2053261733] 'process raft request' (duration: 22.096543ms)","trace[2053261733] 'compare' (duration: 89.302222ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:10.033541Z","caller":"traceutil/trace.go:171","msg":"trace[1779338104] linearizableReadLoop","detail":"{readStateIndex:3214; appliedIndex:3212; }","duration":"110.811912ms","start":"2026-04-14T06:31:09.922693Z","end":"2026-04-14T06:31:10.033505Z","steps":["trace[1779338104] 'read index received' (duration: 21.142502ms)","trace[1779338104] 'applied index is now lower than readState.Index' (duration: 89.66721ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:10.03371Z","caller":"traceutil/trace.go:171","msg":"trace[518521491] transaction","detail":"{read_only:false; response_revision:3109; number_of_response:1; }","duration":"111.515128ms","start":"2026-04-14T06:31:09.922175Z","end":"2026-04-14T06:31:10.03369Z","steps":["trace[518521491] 'process raft request' (duration: 111.1119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:10.034089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.378504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T06:31:10.034191Z","caller":"traceutil/trace.go:171","msg":"trace[2078482294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3109; }","duration":"111.529747ms","start":"2026-04-14T06:31:09.922641Z","end":"2026-04-14T06:31:10.034171Z","steps":["trace[2078482294] 'agreement among raft nodes before linearized reading' (duration: 111.227621ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:10.213521Z","caller":"traceutil/trace.go:171","msg":"trace[1503439682] transaction","detail":"{read_only:false; response_revision:3110; number_of_response:1; }","duration":"176.208864ms","start":"2026-04-14T06:31:10.037271Z","end":"2026-04-14T06:31:10.21348Z","steps":["trace[1503439682] 'process raft request' (duration: 175.047149ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:10.213961Z","caller":"traceutil/trace.go:171","msg":"trace[1966349677] transaction","detail":"{read_only:false; response_revision:3111; number_of_response:1; }","duration":"174.950587ms","start":"2026-04-14T06:31:10.038992Z","end":"2026-04-14T06:31:10.213943Z","steps":["trace[1966349677] 'process raft request' (duration: 174.856935ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:32:38.30847Z","caller":"traceutil/trace.go:171","msg":"trace[793027657] transaction","detail":"{read_only:false; response_revision:3651; number_of_response:1; }","duration":"112.952818ms","start":"2026-04-14T06:32:38.195488Z","end":"2026-04-14T06:32:38.308441Z","steps":["trace[793027657] 'process raft request' (duration: 112.796154ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:36:54.331468Z","caller":"traceutil/trace.go:171","msg":"trace[1009113065] transaction","detail":"{read_only:false; response_revision:5897; number_of_response:1; }","duration":"116.098555ms","start":"2026-04-14T06:36:54.215306Z","end":"2026-04-14T06:36:54.331405Z","steps":["trace[1009113065] 'process raft request' (duration: 115.771747ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:37:04.271421Z","caller":"traceutil/trace.go:171","msg":"trace[1992565501] transaction","detail":"{read_only:false; response_revision:6114; number_of_response:1; }","duration":"111.27574ms","start":"2026-04-14T06:37:04.160089Z","end":"2026-04-14T06:37:04.271365Z","steps":["trace[1992565501] 'process raft request' (duration: 61.314782ms)","trace[1992565501] 'compare' (duration: 49.698883ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:37:04.442408Z","caller":"traceutil/trace.go:171","msg":"trace[1372148281] transaction","detail":"{read_only:false; response_revision:6117; number_of_response:1; }","duration":"110.161795ms","start":"2026-04-14T06:37:04.332197Z","end":"2026-04-14T06:37:04.442358Z","steps":["trace[1372148281] 'process raft request' (duration: 109.638054ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:37:50.254654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3712} {"level":"info","ts":"2026-04-14T06:37:50.357173Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3712,"took":"100.730782ms","hash":4051677150,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":33943552,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T06:37:50.357278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4051677150,"revision":3712,"compact-revision":-1} {"level":"info","ts":"2026-04-14T06:39:05.196816Z","caller":"traceutil/trace.go:171","msg":"trace[1072905180] transaction","detail":"{read_only:false; response_revision:7488; number_of_response:1; }","duration":"130.506562ms","start":"2026-04-14T06:39:05.066282Z","end":"2026-04-14T06:39:05.196788Z","steps":["trace[1072905180] 'process raft request' (duration: 130.313728ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:39:19.748449Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000001f27.wal"} {"level":"warn","ts":"2026-04-14T06:39:35.459972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.195713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T06:39:35.460134Z","caller":"traceutil/trace.go:171","msg":"trace[2098235340] linearizableReadLoop","detail":"{readStateIndex:8606; appliedIndex:8605; }","duration":"135.53607ms","start":"2026-04-14T06:39:35.324575Z","end":"2026-04-14T06:39:35.460111Z","steps":["trace[2098235340] 'read index received' (duration: 27.979168ms)","trace[2098235340] 'applied index is now lower than readState.Index' (duration: 107.554912ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:39:35.460143Z","caller":"traceutil/trace.go:171","msg":"trace[1030697910] transaction","detail":"{read_only:false; response_revision:8347; number_of_response:1; }","duration":"136.47634ms","start":"2026-04-14T06:39:35.323649Z","end":"2026-04-14T06:39:35.460126Z","steps":["trace[1030697910] 'process raft request' (duration: 29.020721ms)","trace[1030697910] 'compare' (duration: 107.0255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:39:35.460648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.051281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-14T06:39:35.460807Z","caller":"traceutil/trace.go:171","msg":"trace[1107694127] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:8347; }","duration":"136.253246ms","start":"2026-04-14T06:39:35.324534Z","end":"2026-04-14T06:39:35.460788Z","steps":["trace[1107694127] 'agreement among raft nodes before linearized reading' (duration: 135.861288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:39:35.462853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.640245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-db-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:39:35.462945Z","caller":"traceutil/trace.go:171","msg":"trace[54470671] range","detail":"{range_begin:/registry/jobs/openstack/barbican-db-init; range_end:; response_count:0; response_revision:8347; }","duration":"137.752818ms","start":"2026-04-14T06:39:35.325172Z","end":"2026-04-14T06:39:35.462925Z","steps":["trace[54470671] 'agreement among raft nodes before linearized reading' (duration: 137.522303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:39:35.463427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.705622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-14T06:39:35.46408Z","caller":"traceutil/trace.go:171","msg":"trace[1227069008] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:8347; }","duration":"120.414767ms","start":"2026-04-14T06:39:35.34365Z","end":"2026-04-14T06:39:35.464065Z","steps":["trace[1227069008] 'agreement among raft nodes before linearized reading' (duration: 119.710372ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:39:35.742787Z","caller":"traceutil/trace.go:171","msg":"trace[1899435937] transaction","detail":"{read_only:false; response_revision:8348; number_of_response:1; }","duration":"272.875429ms","start":"2026-04-14T06:39:35.469885Z","end":"2026-04-14T06:39:35.742761Z","steps":["trace[1899435937] 'process raft request' (duration: 272.706375ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:39:35.789511Z","caller":"traceutil/trace.go:171","msg":"trace[1199262583] linearizableReadLoop","detail":"{readStateIndex:8609; appliedIndex:8606; }","duration":"312.254461ms","start":"2026-04-14T06:39:35.477228Z","end":"2026-04-14T06:39:35.789482Z","steps":["trace[1199262583] 'read index received' (duration: 265.375368ms)","trace[1199262583] 'applied index is now lower than readState.Index' (duration: 46.877653ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:39:35.789794Z","caller":"traceutil/trace.go:171","msg":"trace[1304821069] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"294.337648ms","start":"2026-04-14T06:39:35.495407Z","end":"2026-04-14T06:39:35.789744Z","steps":["trace[1304821069] 'process raft request' (duration: 293.862099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:39:35.790047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.793872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:39:35.790118Z","caller":"traceutil/trace.go:171","msg":"trace[1386439171] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:8352; }","duration":"312.918686ms","start":"2026-04-14T06:39:35.477182Z","end":"2026-04-14T06:39:35.7901Z","steps":["trace[1386439171] 'agreement among raft nodes before linearized reading' (duration: 312.787383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:39:35.790155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:39:35.477164Z","time spent":"312.982068ms","remote":"127.0.0.1:54432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" "} {"level":"info","ts":"2026-04-14T06:39:35.790255Z","caller":"traceutil/trace.go:171","msg":"trace[1490448091] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"318.121327ms","start":"2026-04-14T06:39:35.472123Z","end":"2026-04-14T06:39:35.790245Z","steps":["trace[1490448091] 'process raft request' (duration: 316.911472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:39:35.790398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:39:35.472105Z","time spent":"318.201958ms","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7208,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:39:35.790481Z","caller":"traceutil/trace.go:171","msg":"trace[1405445752] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"290.439305ms","start":"2026-04-14T06:39:35.500022Z","end":"2026-04-14T06:39:35.790461Z","steps":["trace[1405445752] 'process raft request' (duration: 289.378672ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:39:35.790559Z","caller":"traceutil/trace.go:171","msg":"trace[1717127510] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"313.392626ms","start":"2026-04-14T06:39:35.477159Z","end":"2026-04-14T06:39:35.790552Z","steps":["trace[1717127510] 'process raft request' (duration: 312.045958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:39:35.790601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:39:35.477144Z","time spent":"313.440957ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6648,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T06:39:35.791218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.473162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-04-14T06:39:35.79126Z","caller":"traceutil/trace.go:171","msg":"trace[250557224] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8352; }","duration":"244.561374ms","start":"2026-04-14T06:39:35.546687Z","end":"2026-04-14T06:39:35.791248Z","steps":["trace[250557224] 'agreement among raft nodes before linearized reading' (duration: 243.669134ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:41:33.538149Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:41:33.544413Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-14T06:41:33.544541Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-14T06:42:50.262715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6456} {"level":"info","ts":"2026-04-14T06:42:50.35403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6456,"took":"88.701483ms","hash":575058678,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":35581952,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-14T06:42:50.354133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":575058678,"revision":6456,"compact-revision":3712} {"level":"info","ts":"2026-04-14T06:44:59.338238Z","caller":"traceutil/trace.go:171","msg":"trace[1627426324] transaction","detail":"{read_only:false; response_revision:11677; number_of_response:1; }","duration":"168.571939ms","start":"2026-04-14T06:44:59.169617Z","end":"2026-04-14T06:44:59.338189Z","steps":["trace[1627426324] 'process raft request' (duration: 168.337025ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:44:59.338519Z","caller":"traceutil/trace.go:171","msg":"trace[995948047] linearizableReadLoop","detail":"{readStateIndex:12027; appliedIndex:12027; }","duration":"135.607605ms","start":"2026-04-14T06:44:59.202883Z","end":"2026-04-14T06:44:59.33849Z","steps":["trace[995948047] 'read index received' (duration: 135.600815ms)","trace[995948047] 'applied index is now lower than readState.Index' (duration: 5.32µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:44:59.338741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.84724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-14T06:44:59.338792Z","caller":"traceutil/trace.go:171","msg":"trace[263608059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11677; }","duration":"135.980383ms","start":"2026-04-14T06:44:59.202797Z","end":"2026-04-14T06:44:59.338777Z","steps":["trace[263608059] 'agreement among raft nodes before linearized reading' (duration: 135.82342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:44:59.398809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.300978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-14T06:44:59.398829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.424112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jxt9z\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-14T06:44:59.398899Z","caller":"traceutil/trace.go:171","msg":"trace[295513189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11678; }","duration":"195.464191ms","start":"2026-04-14T06:44:59.203416Z","end":"2026-04-14T06:44:59.39888Z","steps":["trace[295513189] 'agreement among raft nodes before linearized reading' (duration: 195.182965ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:44:59.398913Z","caller":"traceutil/trace.go:171","msg":"trace[441726744] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jxt9z; range_end:; response_count:1; response_revision:11678; }","duration":"151.573185ms","start":"2026-04-14T06:44:59.247323Z","end":"2026-04-14T06:44:59.398896Z","steps":["trace[441726744] 'agreement among raft nodes before linearized reading' (duration: 151.293149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:44:59.39906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.327697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-14T06:44:59.399095Z","caller":"traceutil/trace.go:171","msg":"trace[1657508147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11678; }","duration":"193.380768ms","start":"2026-04-14T06:44:59.205704Z","end":"2026-04-14T06:44:59.399085Z","steps":["trace[1657508147] 'agreement among raft nodes before linearized reading' (duration: 193.293056ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:44:59.399064Z","caller":"traceutil/trace.go:171","msg":"trace[1755888019] transaction","detail":"{read_only:false; response_revision:11678; number_of_response:1; }","duration":"104.49235ms","start":"2026-04-14T06:44:59.294538Z","end":"2026-04-14T06:44:59.399031Z","steps":["trace[1755888019] 'process raft request' (duration: 100.23171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:44:59.399174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.495822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-14T06:44:59.399202Z","caller":"traceutil/trace.go:171","msg":"trace[897381912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11678; }","duration":"195.580164ms","start":"2026-04-14T06:44:59.203615Z","end":"2026-04-14T06:44:59.399195Z","steps":["trace[897381912] 'agreement among raft nodes before linearized reading' (duration: 195.511362ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:04.85271Z","caller":"traceutil/trace.go:171","msg":"trace[752689139] linearizableReadLoop","detail":"{readStateIndex:12353; appliedIndex:12352; }","duration":"160.739391ms","start":"2026-04-14T06:45:04.691943Z","end":"2026-04-14T06:45:04.852683Z","steps":["trace[752689139] 'read index received' (duration: 160.525066ms)","trace[752689139] 'applied index is now lower than readState.Index' (duration: 213.545µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:45:04.852843Z","caller":"traceutil/trace.go:171","msg":"trace[1886251098] transaction","detail":"{read_only:false; response_revision:11999; number_of_response:1; }","duration":"226.716139ms","start":"2026-04-14T06:45:04.626078Z","end":"2026-04-14T06:45:04.852794Z","steps":["trace[1886251098] 'process raft request' (duration: 226.445984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:04.852979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.625567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:45:04.853018Z","caller":"traceutil/trace.go:171","msg":"trace[672238681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11999; }","duration":"136.720539ms","start":"2026-04-14T06:45:04.716287Z","end":"2026-04-14T06:45:04.853008Z","steps":["trace[672238681] 'agreement among raft nodes before linearized reading' (duration: 136.654417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:04.852977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.024406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-m8f9b\" ","response":"range_response_count:1 size:22020"} {"level":"info","ts":"2026-04-14T06:45:04.853177Z","caller":"traceutil/trace.go:171","msg":"trace[2146895675] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-m8f9b; range_end:; response_count:1; response_revision:11999; }","duration":"161.305383ms","start":"2026-04-14T06:45:04.691866Z","end":"2026-04-14T06:45:04.853171Z","steps":["trace[2146895675] 'agreement among raft nodes before linearized reading' (duration: 160.966236ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:07.579438Z","caller":"traceutil/trace.go:171","msg":"trace[646065154] linearizableReadLoop","detail":"{readStateIndex:12384; appliedIndex:12383; }","duration":"131.18269ms","start":"2026-04-14T06:45:07.448227Z","end":"2026-04-14T06:45:07.57941Z","steps":["trace[646065154] 'read index received' (duration: 131.115138ms)","trace[646065154] 'applied index is now lower than readState.Index' (duration: 66.062µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:45:07.579682Z","caller":"traceutil/trace.go:171","msg":"trace[102975360] transaction","detail":"{read_only:false; response_revision:12029; number_of_response:1; }","duration":"135.065184ms","start":"2026-04-14T06:45:07.444581Z","end":"2026-04-14T06:45:07.579647Z","steps":["trace[102975360] 'process raft request' (duration: 134.645174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.580075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.826404ms","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-04-14T06:45:07.580157Z","caller":"traceutil/trace.go:171","msg":"trace[852694467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12029; }","duration":"131.954187ms","start":"2026-04-14T06:45:07.448182Z","end":"2026-04-14T06:45:07.580136Z","steps":["trace[852694467] 'agreement among raft nodes before linearized reading' (duration: 131.478637ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:07.919328Z","caller":"traceutil/trace.go:171","msg":"trace[375590169] linearizableReadLoop","detail":"{readStateIndex:12385; appliedIndex:12384; }","duration":"339.784103ms","start":"2026-04-14T06:45:07.579519Z","end":"2026-04-14T06:45:07.919304Z","steps":["trace[375590169] 'read index received' (duration: 256.648708ms)","trace[375590169] 'applied index is now lower than readState.Index' (duration: 83.134245ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:45:07.919334Z","caller":"traceutil/trace.go:171","msg":"trace[1630968911] transaction","detail":"{read_only:false; response_revision:12030; number_of_response:1; }","duration":"385.298225ms","start":"2026-04-14T06:45:07.53399Z","end":"2026-04-14T06:45:07.919288Z","steps":["trace[1630968911] 'process raft request' (duration: 302.079058ms)","trace[1630968911] 'compare' (duration: 82.90204ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:45:07.919551Z","caller":"traceutil/trace.go:171","msg":"trace[537366144] transaction","detail":"{read_only:false; response_revision:12031; number_of_response:1; }","duration":"333.761005ms","start":"2026-04-14T06:45:07.585767Z","end":"2026-04-14T06:45:07.919528Z","steps":["trace[537366144] 'process raft request' (duration: 333.491559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.919696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.585747Z","time spent":"333.843676ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:45:07.919681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.672876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-14T06:45:07.919813Z","caller":"traceutil/trace.go:171","msg":"trace[2098703210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12031; }","duration":"469.830989ms","start":"2026-04-14T06:45:07.449961Z","end":"2026-04-14T06:45:07.919792Z","steps":["trace[2098703210] 'agreement among raft nodes before linearized reading' (duration: 469.510693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.919875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.44995Z","time spent":"469.908562ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:45:07.919887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.931381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:45:07.919891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.485417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:45:07.919931Z","caller":"traceutil/trace.go:171","msg":"trace[1136729489] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:12031; }","duration":"395.054593ms","start":"2026-04-14T06:45:07.524864Z","end":"2026-04-14T06:45:07.919918Z","steps":["trace[1136729489] 'agreement among raft nodes before linearized reading' (duration: 394.554212ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:07.919946Z","caller":"traceutil/trace.go:171","msg":"trace[1888475176] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:12031; }","duration":"413.601519ms","start":"2026-04-14T06:45:07.50633Z","end":"2026-04-14T06:45:07.919932Z","steps":["trace[1888475176] 'agreement among raft nodes before linearized reading' (duration: 413.022197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.919968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.524837Z","time spent":"395.125225ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-04-14T06:45:07.919977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.506293Z","time spent":"413.677821ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-04-14T06:45:07.919942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.04406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:45:07.920036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.98742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-04-14T06:45:07.920095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.293525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:45:07.920077Z","caller":"traceutil/trace.go:171","msg":"trace[1602893660] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:12031; }","duration":"341.249935ms","start":"2026-04-14T06:45:07.578795Z","end":"2026-04-14T06:45:07.920045Z","steps":["trace[1602893660] 'agreement among raft nodes before linearized reading' (duration: 340.601131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.850745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:45:07.920116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.27823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-14T06:45:07.92016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.57877Z","time spent":"341.378448ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-04-14T06:45:07.92017Z","caller":"traceutil/trace.go:171","msg":"trace[1879559953] transaction","detail":"{read_only:false; response_revision:12032; number_of_response:1; }","duration":"275.738295ms","start":"2026-04-14T06:45:07.644421Z","end":"2026-04-14T06:45:07.920159Z","steps":["trace[1879559953] 'process raft request' (duration: 275.074011ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:07.920155Z","caller":"traceutil/trace.go:171","msg":"trace[964955814] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12031; }","duration":"341.183263ms","start":"2026-04-14T06:45:07.578943Z","end":"2026-04-14T06:45:07.920126Z","steps":["trace[964955814] 'agreement among raft nodes before linearized reading' (duration: 340.439418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.279599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:45:07.920188Z","caller":"traceutil/trace.go:171","msg":"trace[1204980008] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:12031; }","duration":"413.991387ms","start":"2026-04-14T06:45:07.506181Z","end":"2026-04-14T06:45:07.920173Z","steps":["trace[1204980008] 'agreement among raft nodes before linearized reading' (duration: 413.269582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.861545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:45:07.920174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.498198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-14T06:45:07.920219Z","caller":"traceutil/trace.go:171","msg":"trace[1797756212] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:12032; }","duration":"203.3271ms","start":"2026-04-14T06:45:07.716884Z","end":"2026-04-14T06:45:07.920211Z","steps":["trace[1797756212] 'agreement among raft nodes before linearized reading' (duration: 203.283249ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:07.920227Z","caller":"traceutil/trace.go:171","msg":"trace[1070422454] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12031; }","duration":"413.932776ms","start":"2026-04-14T06:45:07.506288Z","end":"2026-04-14T06:45:07.920221Z","steps":["trace[1070422454] 'agreement among raft nodes before linearized reading' (duration: 413.1551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.92022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.578915Z","time spent":"341.295836ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-04-14T06:45:07.920231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.506148Z","time spent":"414.074239ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"info","ts":"2026-04-14T06:45:07.920221Z","caller":"traceutil/trace.go:171","msg":"trace[1720260488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12031; }","duration":"471.454694ms","start":"2026-04-14T06:45:07.448733Z","end":"2026-04-14T06:45:07.920188Z","steps":["trace[1720260488] 'agreement among raft nodes before linearized reading' (duration: 470.727358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.506261Z","time spent":"413.988268ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-04-14T06:45:07.920261Z","caller":"traceutil/trace.go:171","msg":"trace[1170598996] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:12032; }","duration":"310.663142ms","start":"2026-04-14T06:45:07.609579Z","end":"2026-04-14T06:45:07.920243Z","steps":["trace[1170598996] 'agreement among raft nodes before linearized reading' (duration: 310.334115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.025218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-14T06:45:07.920137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.511444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:45:07.920332Z","caller":"traceutil/trace.go:171","msg":"trace[815376705] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12031; }","duration":"466.091049ms","start":"2026-04-14T06:45:07.45423Z","end":"2026-04-14T06:45:07.920321Z","steps":["trace[815376705] 'agreement among raft nodes before linearized reading' (duration: 465.215131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.448686Z","time spent":"471.587006ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-14T06:45:07.920328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.609556Z","time spent":"310.762503ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-04-14T06:45:07.920336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.897823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:45:07.92037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.842571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T06:45:07.920381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.617378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:45:07.920387Z","caller":"traceutil/trace.go:171","msg":"trace[1147679907] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12032; }","duration":"203.971135ms","start":"2026-04-14T06:45:07.716408Z","end":"2026-04-14T06:45:07.920379Z","steps":["trace[1147679907] 'agreement among raft nodes before linearized reading' (duration: 203.908063ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:07.920402Z","caller":"traceutil/trace.go:171","msg":"trace[556269505] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:12032; }","duration":"203.914783ms","start":"2026-04-14T06:45:07.71648Z","end":"2026-04-14T06:45:07.920395Z","steps":["trace[556269505] 'agreement among raft nodes before linearized reading' (duration: 203.866722ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:07.920136Z","caller":"traceutil/trace.go:171","msg":"trace[1146007289] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:12031; }","duration":"341.370527ms","start":"2026-04-14T06:45:07.578754Z","end":"2026-04-14T06:45:07.920124Z","steps":["trace[1146007289] 'agreement among raft nodes before linearized reading' (duration: 340.654682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.578726Z","time spent":"341.718555ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-04-14T06:45:07.920406Z","caller":"traceutil/trace.go:171","msg":"trace[1434502605] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:0; response_revision:12032; }","duration":"204.680569ms","start":"2026-04-14T06:45:07.715719Z","end":"2026-04-14T06:45:07.920399Z","steps":["trace[1434502605] 'agreement among raft nodes before linearized reading' (duration: 204.644868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.994637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jxt9z\" ","response":"range_response_count:1 size:6451"} {"level":"info","ts":"2026-04-14T06:45:07.920524Z","caller":"traceutil/trace.go:171","msg":"trace[222975149] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jxt9z; range_end:; response_count:1; response_revision:12031; }","duration":"428.569048ms","start":"2026-04-14T06:45:07.491943Z","end":"2026-04-14T06:45:07.920513Z","steps":["trace[222975149] 'agreement among raft nodes before linearized reading' (duration: 427.489486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.491916Z","time spent":"428.62487ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6474,"request content":"key:\"/registry/pods/openstack/keepalived-jxt9z\" "} {"level":"warn","ts":"2026-04-14T06:45:07.920375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.454214Z","time spent":"466.15392ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-14T06:45:07.920343Z","caller":"traceutil/trace.go:171","msg":"trace[1512963773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12032; }","duration":"203.7563ms","start":"2026-04-14T06:45:07.716574Z","end":"2026-04-14T06:45:07.92033Z","steps":["trace[1512963773] 'agreement among raft nodes before linearized reading' (duration: 203.535345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:07.920799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:07.533955Z","time spent":"385.495139ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:45:14.496016Z","caller":"traceutil/trace.go:171","msg":"trace[1228302060] transaction","detail":"{read_only:false; response_revision:12133; number_of_response:1; }","duration":"105.859298ms","start":"2026-04-14T06:45:14.390119Z","end":"2026-04-14T06:45:14.495978Z","steps":["trace[1228302060] 'process raft request' (duration: 71.528106ms)","trace[1228302060] 'compare' (duration: 33.399533ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:45:26.722046Z","caller":"traceutil/trace.go:171","msg":"trace[835768276] transaction","detail":"{read_only:false; response_revision:12247; number_of_response:1; }","duration":"142.071163ms","start":"2026-04-14T06:45:26.579952Z","end":"2026-04-14T06:45:26.722023Z","steps":["trace[835768276] 'process raft request' (duration: 141.677475ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:27.114823Z","caller":"traceutil/trace.go:171","msg":"trace[887186443] transaction","detail":"{read_only:false; response_revision:12249; number_of_response:1; }","duration":"124.744624ms","start":"2026-04-14T06:45:26.990044Z","end":"2026-04-14T06:45:27.114788Z","steps":["trace[887186443] 'process raft request' (duration: 124.526919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.376868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.799917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-m8f9b\" ","response":"range_response_count:1 size:22020"} {"level":"info","ts":"2026-04-14T06:45:27.376963Z","caller":"traceutil/trace.go:171","msg":"trace[800334742] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-m8f9b; range_end:; response_count:1; response_revision:12249; }","duration":"163.93859ms","start":"2026-04-14T06:45:27.213005Z","end":"2026-04-14T06:45:27.376944Z","steps":["trace[800334742] 'range keys from in-memory index tree' (duration: 163.46318ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:27.839922Z","caller":"traceutil/trace.go:171","msg":"trace[1027472864] linearizableReadLoop","detail":"{readStateIndex:12614; appliedIndex:12613; }","duration":"453.646373ms","start":"2026-04-14T06:45:27.386254Z","end":"2026-04-14T06:45:27.839901Z","steps":["trace[1027472864] 'read index received' (duration: 453.555921ms)","trace[1027472864] 'applied index is now lower than readState.Index' (duration: 89.302µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:45:27.839994Z","caller":"traceutil/trace.go:171","msg":"trace[1766856922] transaction","detail":"{read_only:false; response_revision:12250; number_of_response:1; }","duration":"467.663583ms","start":"2026-04-14T06:45:27.372292Z","end":"2026-04-14T06:45:27.839956Z","steps":["trace[1766856922] 'process raft request' (duration: 467.461659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.840171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:27.372256Z","time spent":"467.818586ms","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:45:27.840759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.495581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:93 size:875774"} {"level":"info","ts":"2026-04-14T06:45:27.840792Z","caller":"traceutil/trace.go:171","msg":"trace[464333002] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:93; response_revision:12250; }","duration":"454.602464ms","start":"2026-04-14T06:45:27.386182Z","end":"2026-04-14T06:45:27.840784Z","steps":["trace[464333002] 'agreement among raft nodes before linearized reading' (duration: 453.829028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.840819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:27.386159Z","time spent":"454.652634ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":93,"response size":875797,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-04-14T06:45:27.912855Z","caller":"traceutil/trace.go:171","msg":"trace[751592481] transaction","detail":"{read_only:false; response_revision:12251; number_of_response:1; }","duration":"229.640831ms","start":"2026-04-14T06:45:27.683121Z","end":"2026-04-14T06:45:27.912762Z","steps":["trace[751592481] 'process raft request' (duration: 197.134047ms)","trace[751592481] 'compare' (duration: 32.119206ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:45:27.913133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.305811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:45:27.913258Z","caller":"traceutil/trace.go:171","msg":"trace[1727818749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12252; }","duration":"197.485064ms","start":"2026-04-14T06:45:27.715744Z","end":"2026-04-14T06:45:27.913229Z","steps":["trace[1727818749] 'agreement among raft nodes before linearized reading' (duration: 197.225459ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:45:27.913241Z","caller":"traceutil/trace.go:171","msg":"trace[438301619] transaction","detail":"{read_only:false; response_revision:12252; number_of_response:1; }","duration":"179.992602ms","start":"2026-04-14T06:45:27.733181Z","end":"2026-04-14T06:45:27.913173Z","steps":["trace[438301619] 'process raft request' (duration: 179.525012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.913298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.201005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T06:45:27.913351Z","caller":"traceutil/trace.go:171","msg":"trace[648182442] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:12252; }","duration":"135.309168ms","start":"2026-04-14T06:45:27.778027Z","end":"2026-04-14T06:45:27.913336Z","steps":["trace[648182442] 'agreement among raft nodes before linearized reading' (duration: 135.179965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.913374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.814007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-14T06:45:27.913348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.588837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-14T06:45:27.913464Z","caller":"traceutil/trace.go:171","msg":"trace[1581020622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12252; }","duration":"390.753461ms","start":"2026-04-14T06:45:27.522697Z","end":"2026-04-14T06:45:27.91345Z","steps":["trace[1581020622] 'agreement among raft nodes before linearized reading' (duration: 390.352232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.913534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:27.522673Z","time spent":"390.849632ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-14T06:45:27.913465Z","caller":"traceutil/trace.go:171","msg":"trace[1814266628] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:12252; }","duration":"453.9546ms","start":"2026-04-14T06:45:27.459491Z","end":"2026-04-14T06:45:27.913446Z","steps":["trace[1814266628] 'agreement among raft nodes before linearized reading' (duration: 453.792846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.913596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:27.459477Z","time spent":"454.110153ms","remote":"127.0.0.1:48416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":4,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:45:27.91387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.139844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-04-14T06:45:27.913896Z","caller":"traceutil/trace.go:171","msg":"trace[1006946794] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:12252; }","duration":"521.188115ms","start":"2026-04-14T06:45:27.392702Z","end":"2026-04-14T06:45:27.91389Z","steps":["trace[1006946794] 'agreement among raft nodes before linearized reading' (duration: 521.134104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:45:27.913913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:45:27.392692Z","time spent":"521.216716ms","remote":"127.0.0.1:54594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5471,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-04-14T06:47:50.271961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10462} {"level":"info","ts":"2026-04-14T06:47:50.400808Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10462,"took":"124.408627ms","hash":1759044061,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":30842880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T06:47:50.400904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1759044061,"revision":10462,"compact-revision":6456} {"level":"info","ts":"2026-04-14T06:52:16.976847Z","caller":"traceutil/trace.go:171","msg":"trace[1134599096] transaction","detail":"{read_only:false; response_revision:15281; number_of_response:1; }","duration":"101.802437ms","start":"2026-04-14T06:52:16.875004Z","end":"2026-04-14T06:52:16.976806Z","steps":["trace[1134599096] 'process raft request' (duration: 101.549102ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:52:17.853758Z","caller":"traceutil/trace.go:171","msg":"trace[2056411335] linearizableReadLoop","detail":"{readStateIndex:15745; appliedIndex:15744; }","duration":"217.417769ms","start":"2026-04-14T06:52:17.63631Z","end":"2026-04-14T06:52:17.853728Z","steps":["trace[2056411335] 'read index received' (duration: 217.119093ms)","trace[2056411335] 'applied index is now lower than readState.Index' (duration: 296.296µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:52:17.854121Z","caller":"traceutil/trace.go:171","msg":"trace[1343397516] transaction","detail":"{read_only:false; response_revision:15282; number_of_response:1; }","duration":"297.308026ms","start":"2026-04-14T06:52:17.556798Z","end":"2026-04-14T06:52:17.854106Z","steps":["trace[1343397516] 'process raft request' (duration: 296.523499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:52:17.854549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.224905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-m8f9b\" ","response":"range_response_count:1 size:22020"} {"level":"info","ts":"2026-04-14T06:52:17.854604Z","caller":"traceutil/trace.go:171","msg":"trace[164874859] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-m8f9b; range_end:; response_count:1; response_revision:15282; }","duration":"218.339938ms","start":"2026-04-14T06:52:17.636253Z","end":"2026-04-14T06:52:17.854593Z","steps":["trace[164874859] 'agreement among raft nodes before linearized reading' (duration: 218.124843ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:52:18.067735Z","caller":"traceutil/trace.go:171","msg":"trace[1047896371] linearizableReadLoop","detail":"{readStateIndex:15747; appliedIndex:15745; }","duration":"213.859592ms","start":"2026-04-14T06:52:17.853849Z","end":"2026-04-14T06:52:18.067708Z","steps":["trace[1047896371] 'read index received' (duration: 213.668228ms)","trace[1047896371] 'applied index is now lower than readState.Index' (duration: 190.024µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:52:18.067789Z","caller":"traceutil/trace.go:171","msg":"trace[405473438] transaction","detail":"{read_only:false; response_revision:15283; number_of_response:1; }","duration":"360.301812ms","start":"2026-04-14T06:52:17.707456Z","end":"2026-04-14T06:52:18.067758Z","steps":["trace[405473438] 'process raft request' (duration: 360.048366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:52:18.06791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.700968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:52:18.067945Z","caller":"traceutil/trace.go:171","msg":"trace[971451643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15284; }","duration":"351.78431ms","start":"2026-04-14T06:52:17.716151Z","end":"2026-04-14T06:52:18.067936Z","steps":["trace[971451643] 'agreement among raft nodes before linearized reading' (duration: 351.710728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:52:18.067945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:52:17.707428Z","time spent":"360.417874ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:52:18.06788Z","caller":"traceutil/trace.go:171","msg":"trace[1852533241] transaction","detail":"{read_only:false; response_revision:15284; number_of_response:1; }","duration":"360.383823ms","start":"2026-04-14T06:52:17.707455Z","end":"2026-04-14T06:52:18.067839Z","steps":["trace[1852533241] 'process raft request' (duration: 360.21088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:52:18.068224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:52:17.707428Z","time spent":"360.611838ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:52:18.068529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.740324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kz9qk\" ","response":"range_response_count:1 size:9712"} {"level":"info","ts":"2026-04-14T06:52:18.068641Z","caller":"traceutil/trace.go:171","msg":"trace[229633649] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kz9qk; range_end:; response_count:1; response_revision:15284; }","duration":"202.864166ms","start":"2026-04-14T06:52:17.865751Z","end":"2026-04-14T06:52:18.068615Z","steps":["trace[229633649] 'agreement among raft nodes before linearized reading' (duration: 202.442337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:52:18.069202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.410655ms","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:5"} {"level":"info","ts":"2026-04-14T06:52:18.069254Z","caller":"traceutil/trace.go:171","msg":"trace[1220862391] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:15284; }","duration":"216.705213ms","start":"2026-04-14T06:52:17.852537Z","end":"2026-04-14T06:52:18.069242Z","steps":["trace[1220862391] 'agreement among raft nodes before linearized reading' (duration: 215.331723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:52:18.067977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:52:17.716133Z","time spent":"351.83832ms","remote":"127.0.0.1:54322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T06:52:18.069393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.758377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:113 size:1115824"} {"level":"info","ts":"2026-04-14T06:52:18.069502Z","caller":"traceutil/trace.go:171","msg":"trace[1395272396] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:113; response_revision:15284; }","duration":"204.91631ms","start":"2026-04-14T06:52:17.864571Z","end":"2026-04-14T06:52:18.069488Z","steps":["trace[1395272396] 'agreement among raft nodes before linearized reading' (duration: 203.54681ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:52:50.280357Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13199} {"level":"info","ts":"2026-04-14T06:52:50.399489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13199,"took":"115.117673ms","hash":1450347059,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":32546816,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T06:52:50.399586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1450347059,"revision":13199,"compact-revision":10462} {"level":"info","ts":"2026-04-14T06:53:14.495927Z","caller":"traceutil/trace.go:171","msg":"trace[757484095] transaction","detail":"{read_only:false; response_revision:15914; number_of_response:1; }","duration":"106.881625ms","start":"2026-04-14T06:53:14.389011Z","end":"2026-04-14T06:53:14.495893Z","steps":["trace[757484095] 'process raft request' (duration: 106.690501ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:53:15.381449Z","caller":"traceutil/trace.go:171","msg":"trace[1781241234] transaction","detail":"{read_only:false; response_revision:15925; number_of_response:1; }","duration":"152.663364ms","start":"2026-04-14T06:53:15.228758Z","end":"2026-04-14T06:53:15.381421Z","steps":["trace[1781241234] 'process raft request' (duration: 152.44471ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:53:16.526823Z","caller":"traceutil/trace.go:171","msg":"trace[2069386271] transaction","detail":"{read_only:false; response_revision:15933; number_of_response:1; }","duration":"123.796687ms","start":"2026-04-14T06:53:16.402992Z","end":"2026-04-14T06:53:16.526789Z","steps":["trace[2069386271] 'process raft request' (duration: 123.545072ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:06.52731Z","caller":"traceutil/trace.go:171","msg":"trace[963123252] transaction","detail":"{read_only:false; response_revision:16387; number_of_response:1; }","duration":"110.736048ms","start":"2026-04-14T06:54:06.416521Z","end":"2026-04-14T06:54:06.527257Z","steps":["trace[963123252] 'process raft request' (duration: 110.35628ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:16.941986Z","caller":"traceutil/trace.go:171","msg":"trace[1894990960] transaction","detail":"{read_only:false; response_revision:16440; number_of_response:1; }","duration":"150.697852ms","start":"2026-04-14T06:54:16.791253Z","end":"2026-04-14T06:54:16.941951Z","steps":["trace[1894990960] 'process raft request' (duration: 117.710167ms)","trace[1894990960] 'compare' (duration: 32.524775ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:54:16.98762Z","caller":"traceutil/trace.go:171","msg":"trace[511452952] transaction","detail":"{read_only:false; response_revision:16441; number_of_response:1; }","duration":"132.229857ms","start":"2026-04-14T06:54:16.855353Z","end":"2026-04-14T06:54:16.987583Z","steps":["trace[511452952] 'process raft request' (duration: 131.817628ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:22.538068Z","caller":"traceutil/trace.go:171","msg":"trace[800073387] transaction","detail":"{read_only:false; response_revision:16467; number_of_response:1; }","duration":"114.705352ms","start":"2026-04-14T06:54:22.423321Z","end":"2026-04-14T06:54:22.538026Z","steps":["trace[800073387] 'process raft request' (duration: 114.492808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:54:22.75314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.754578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5005"} {"level":"warn","ts":"2026-04-14T06:54:22.753227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.213487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:54:22.753258Z","caller":"traceutil/trace.go:171","msg":"trace[1831995759] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:16467; }","duration":"182.90839ms","start":"2026-04-14T06:54:22.570327Z","end":"2026-04-14T06:54:22.753235Z","steps":["trace[1831995759] 'range keys from in-memory index tree' (duration: 182.484562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:54:22.753238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.27833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T06:54:22.753285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.209217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:54:22.753388Z","caller":"traceutil/trace.go:171","msg":"trace[547088291] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:16467; }","duration":"183.476964ms","start":"2026-04-14T06:54:22.56988Z","end":"2026-04-14T06:54:22.753357Z","steps":["trace[547088291] 'range keys from in-memory index tree' (duration: 183.111255ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:22.753399Z","caller":"traceutil/trace.go:171","msg":"trace[1553887526] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:16467; }","duration":"183.35396ms","start":"2026-04-14T06:54:22.570024Z","end":"2026-04-14T06:54:22.753378Z","steps":["trace[1553887526] 'range keys from in-memory index tree' (duration: 183.162206ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:22.753309Z","caller":"traceutil/trace.go:171","msg":"trace[1032071861] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:16467; }","duration":"183.31647ms","start":"2026-04-14T06:54:22.569971Z","end":"2026-04-14T06:54:22.753287Z","steps":["trace[1032071861] 'range keys from in-memory index tree' (duration: 183.171806ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:23.109781Z","caller":"traceutil/trace.go:171","msg":"trace[92331584] transaction","detail":"{read_only:false; response_revision:16469; number_of_response:1; }","duration":"136.653762ms","start":"2026-04-14T06:54:22.973086Z","end":"2026-04-14T06:54:23.109739Z","steps":["trace[92331584] 'process raft request' (duration: 129.05925ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:23.254095Z","caller":"traceutil/trace.go:171","msg":"trace[1306447261] linearizableReadLoop","detail":"{readStateIndex:16967; appliedIndex:16966; }","duration":"135.807775ms","start":"2026-04-14T06:54:23.11825Z","end":"2026-04-14T06:54:23.254058Z","steps":["trace[1306447261] 'read index received' (duration: 119.590038ms)","trace[1306447261] 'applied index is now lower than readState.Index' (duration: 16.216427ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:54:23.254161Z","caller":"traceutil/trace.go:171","msg":"trace[245484874] transaction","detail":"{read_only:false; response_revision:16470; number_of_response:1; }","duration":"139.37178ms","start":"2026-04-14T06:54:23.114754Z","end":"2026-04-14T06:54:23.254126Z","steps":["trace[245484874] 'process raft request' (duration: 122.99127ms)","trace[245484874] 'compare' (duration: 16.151425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:54:23.254424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.145062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-14T06:54:23.254484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.855943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:54:23.254517Z","caller":"traceutil/trace.go:171","msg":"trace[1841816606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16470; }","duration":"136.316695ms","start":"2026-04-14T06:54:23.118175Z","end":"2026-04-14T06:54:23.254492Z","steps":["trace[1841816606] 'agreement among raft nodes before linearized reading' (duration: 136.054309ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:23.254539Z","caller":"traceutil/trace.go:171","msg":"trace[113169261] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:16470; }","duration":"134.975016ms","start":"2026-04-14T06:54:23.119547Z","end":"2026-04-14T06:54:23.254522Z","steps":["trace[113169261] 'agreement among raft nodes before linearized reading' (duration: 134.855093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:54:23.255234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.568528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5005"} {"level":"info","ts":"2026-04-14T06:54:23.25528Z","caller":"traceutil/trace.go:171","msg":"trace[2108765193] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:16470; }","duration":"135.65612ms","start":"2026-04-14T06:54:23.119614Z","end":"2026-04-14T06:54:23.25527Z","steps":["trace[2108765193] 'agreement among raft nodes before linearized reading' (duration: 135.480327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:54:23.255519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.838974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:54:23.255554Z","caller":"traceutil/trace.go:171","msg":"trace[962542141] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:16470; }","duration":"135.925796ms","start":"2026-04-14T06:54:23.11962Z","end":"2026-04-14T06:54:23.255546Z","steps":["trace[962542141] 'agreement among raft nodes before linearized reading' (duration: 135.871555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:54:23.255009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.321903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:54:23.257947Z","caller":"traceutil/trace.go:171","msg":"trace[418695677] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:16470; }","duration":"138.299847ms","start":"2026-04-14T06:54:23.119627Z","end":"2026-04-14T06:54:23.257927Z","steps":["trace[418695677] 'agreement among raft nodes before linearized reading' (duration: 135.350574ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:54:23.47249Z","caller":"traceutil/trace.go:171","msg":"trace[1350005461] transaction","detail":"{read_only:false; response_revision:16471; number_of_response:1; }","duration":"211.960713ms","start":"2026-04-14T06:54:23.260498Z","end":"2026-04-14T06:54:23.472458Z","steps":["trace[1350005461] 'process raft request' (duration: 210.875439ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:57:50.288392Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15735} {"level":"info","ts":"2026-04-14T06:57:50.406427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15735,"took":"113.49636ms","hash":1720524147,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":35762176,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-14T06:57:50.406497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1720524147,"revision":15735,"compact-revision":13199} {"level":"info","ts":"2026-04-14T06:58:40.98702Z","caller":"traceutil/trace.go:171","msg":"trace[1202289525] transaction","detail":"{read_only:false; response_revision:19297; number_of_response:1; }","duration":"264.686586ms","start":"2026-04-14T06:58:40.722295Z","end":"2026-04-14T06:58:40.986981Z","steps":["trace[1202289525] 'process raft request' (duration: 264.41157ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:58:41.155053Z","caller":"traceutil/trace.go:171","msg":"trace[336981474] linearizableReadLoop","detail":"{readStateIndex:19877; appliedIndex:19876; }","duration":"161.799066ms","start":"2026-04-14T06:58:40.993216Z","end":"2026-04-14T06:58:41.155015Z","steps":["trace[336981474] 'read index received' (duration: 157.230029ms)","trace[336981474] 'applied index is now lower than readState.Index' (duration: 4.567367ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:58:41.155137Z","caller":"traceutil/trace.go:171","msg":"trace[1567251342] transaction","detail":"{read_only:false; response_revision:19298; number_of_response:1; }","duration":"429.038858ms","start":"2026-04-14T06:58:40.726039Z","end":"2026-04-14T06:58:41.155078Z","steps":["trace[1567251342] 'process raft request' (duration: 424.504971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:58:41.155371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.291388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-14T06:58:41.155441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.781862ms","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-04-14T06:58:41.155477Z","caller":"traceutil/trace.go:171","msg":"trace[1384808572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19298; }","duration":"109.41255ms","start":"2026-04-14T06:58:41.046039Z","end":"2026-04-14T06:58:41.155451Z","steps":["trace[1384808572] 'agreement among raft nodes before linearized reading' (duration: 109.224877ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:58:41.155498Z","caller":"traceutil/trace.go:171","msg":"trace[934440298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19298; }","duration":"132.947956ms","start":"2026-04-14T06:58:41.022526Z","end":"2026-04-14T06:58:41.155474Z","steps":["trace[934440298] 'agreement among raft nodes before linearized reading' (duration: 132.694861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:58:41.155369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.129503ms","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-04-14T06:58:41.155663Z","caller":"traceutil/trace.go:171","msg":"trace[685793776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19298; }","duration":"162.482391ms","start":"2026-04-14T06:58:40.993146Z","end":"2026-04-14T06:58:41.155629Z","steps":["trace[685793776] 'agreement among raft nodes before linearized reading' (duration: 162.037172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:58:41.15573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.372848ms","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-04-14T06:58:41.155782Z","caller":"traceutil/trace.go:171","msg":"trace[536620387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19298; }","duration":"115.46505ms","start":"2026-04-14T06:58:41.040304Z","end":"2026-04-14T06:58:41.155769Z","steps":["trace[536620387] 'agreement among raft nodes before linearized reading' (duration: 115.322967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:58:41.155412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:58:40.726019Z","time spent":"429.270643ms","remote":"127.0.0.1:54620","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-04-14T06:58:54.365748Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"56c01ea8fdc90390","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:58:54.371982Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-14T06:58:54.372213Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-14T06:59:28.180713Z","caller":"traceutil/trace.go:171","msg":"trace[1610531702] linearizableReadLoop","detail":"{readStateIndex:20329; appliedIndex:20329; }","duration":"111.913674ms","start":"2026-04-14T06:59:28.068779Z","end":"2026-04-14T06:59:28.180692Z","steps":["trace[1610531702] 'read index received' (duration: 111.903534ms)","trace[1610531702] 'applied index is now lower than readState.Index' (duration: 8.25µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:59:28.181031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.16513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capo-system/capo-controller-manager-7844f68f-ck9k5\" ","response":"range_response_count:1 size:4568"} {"level":"info","ts":"2026-04-14T06:59:28.181157Z","caller":"traceutil/trace.go:171","msg":"trace[120395393] range","detail":"{range_begin:/registry/pods/capo-system/capo-controller-manager-7844f68f-ck9k5; range_end:; response_count:1; response_revision:19737; }","duration":"112.412395ms","start":"2026-04-14T06:59:28.068721Z","end":"2026-04-14T06:59:28.181134Z","steps":["trace[120395393] 'agreement among raft nodes before linearized reading' (duration: 112.064477ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:59:28.180686Z","caller":"traceutil/trace.go:171","msg":"trace[680724477] transaction","detail":"{read_only:false; response_revision:19737; number_of_response:1; }","duration":"112.589899ms","start":"2026-04-14T06:59:28.068065Z","end":"2026-04-14T06:59:28.180655Z","steps":["trace[680724477] 'process raft request' (duration: 112.417325ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:59:28.182375Z","caller":"traceutil/trace.go:171","msg":"trace[1595274828] transaction","detail":"{read_only:false; response_revision:19738; number_of_response:1; }","duration":"113.596221ms","start":"2026-04-14T06:59:28.068762Z","end":"2026-04-14T06:59:28.182359Z","steps":["trace[1595274828] 'process raft request' (duration: 113.174972ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:59:28.356766Z","caller":"traceutil/trace.go:171","msg":"trace[1744918356] transaction","detail":"{read_only:false; response_revision:19748; number_of_response:1; }","duration":"119.533649ms","start":"2026-04-14T06:59:28.237196Z","end":"2026-04-14T06:59:28.356729Z","steps":["trace[1744918356] 'process raft request' (duration: 83.350161ms)","trace[1744918356] 'compare' (duration: 35.79094ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:38.823557Z","caller":"traceutil/trace.go:171","msg":"trace[2039191475] linearizableReadLoop","detail":"{readStateIndex:22331; appliedIndex:22330; }","duration":"104.928446ms","start":"2026-04-14T07:02:38.718602Z","end":"2026-04-14T07:02:38.823531Z","steps":["trace[2039191475] 'read index received' (duration: 104.722181ms)","trace[2039191475] 'applied index is now lower than readState.Index' (duration: 204.835µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:38.8238Z","caller":"traceutil/trace.go:171","msg":"trace[1461546776] transaction","detail":"{read_only:false; response_revision:21690; number_of_response:1; }","duration":"105.231012ms","start":"2026-04-14T07:02:38.718397Z","end":"2026-04-14T07:02:38.823628Z","steps":["trace[1461546776] 'process raft request' (duration: 104.950546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:38.823823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.195731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:02:38.824224Z","caller":"traceutil/trace.go:171","msg":"trace[242133513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21690; }","duration":"105.585891ms","start":"2026-04-14T07:02:38.718527Z","end":"2026-04-14T07:02:38.824113Z","steps":["trace[242133513] 'agreement among raft nodes before linearized reading' (duration: 105.191202ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:39.183897Z","caller":"traceutil/trace.go:171","msg":"trace[655033314] transaction","detail":"{read_only:false; response_revision:21693; number_of_response:1; }","duration":"354.56481ms","start":"2026-04-14T07:02:38.829295Z","end":"2026-04-14T07:02:39.18386Z","steps":["trace[655033314] 'process raft request' (duration: 354.426807ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:39.184062Z","caller":"traceutil/trace.go:171","msg":"trace[474211767] transaction","detail":"{read_only:false; response_revision:21694; number_of_response:1; }","duration":"348.440899ms","start":"2026-04-14T07:02:38.835591Z","end":"2026-04-14T07:02:39.184032Z","steps":["trace[474211767] 'process raft request' (duration: 348.213994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:39.184104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:38.829283Z","time spent":"354.715633ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:02:39.18431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:38.83557Z","time spent":"348.616182ms","remote":"127.0.0.1:54620","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-04-14T07:02:39.184491Z","caller":"traceutil/trace.go:171","msg":"trace[1351261595] transaction","detail":"{read_only:false; response_revision:21692; number_of_response:1; }","duration":"354.58737ms","start":"2026-04-14T07:02:38.829284Z","end":"2026-04-14T07:02:39.183872Z","steps":["trace[1351261595] 'process raft request' (duration: 354.229953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:39.184565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:38.829268Z","time spent":"355.265985ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:02:39.605912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.739995ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T07:02:39.606709Z","caller":"traceutil/trace.go:171","msg":"trace[987033781] transaction","detail":"{read_only:false; response_revision:21695; number_of_response:1; }","duration":"539.983549ms","start":"2026-04-14T07:02:39.066695Z","end":"2026-04-14T07:02:39.606679Z","steps":["trace[987033781] 'process raft request' (duration: 539.349815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:39.607058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:39.066664Z","time spent":"540.110792ms","remote":"127.0.0.1:54620","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-04-14T07:02:39.740437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.681242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:02:39.740628Z","caller":"traceutil/trace.go:171","msg":"trace[448064750] transaction","detail":"{read_only:false; response_revision:21697; number_of_response:1; }","duration":"360.1349ms","start":"2026-04-14T07:02:39.380475Z","end":"2026-04-14T07:02:39.74061Z","steps":["trace[448064750] 'process raft request' (duration: 360.078009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:39.740682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:39.380441Z","time spent":"360.216901ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:02:39.740896Z","caller":"traceutil/trace.go:171","msg":"trace[1620374546] transaction","detail":"{read_only:false; response_revision:21696; number_of_response:1; }","duration":"598.665455ms","start":"2026-04-14T07:02:39.142222Z","end":"2026-04-14T07:02:39.740887Z","steps":["trace[1620374546] 'process raft request' (duration: 464.441731ms)","trace[1620374546] 'compare' (duration: 133.55566ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:39.740938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:39.142164Z","time spent":"598.754657ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:02:39.774732Z","caller":"traceutil/trace.go:171","msg":"trace[555144283] transaction","detail":"{read_only:false; response_revision:21698; number_of_response:1; }","duration":"223.058115ms","start":"2026-04-14T07:02:39.550853Z","end":"2026-04-14T07:02:39.773911Z","steps":["trace[555144283] 'process raft request' (duration: 222.844539ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:40.407554Z","caller":"traceutil/trace.go:171","msg":"trace[956506371] transaction","detail":"{read_only:false; response_revision:21699; number_of_response:1; }","duration":"169.724243ms","start":"2026-04-14T07:02:40.237786Z","end":"2026-04-14T07:02:40.407511Z","steps":["trace[956506371] 'process raft request' (duration: 168.995517ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:40.407485Z","caller":"traceutil/trace.go:171","msg":"trace[16360030] linearizableReadLoop","detail":"{readStateIndex:22341; appliedIndex:22340; }","duration":"119.259693ms","start":"2026-04-14T07:02:40.288101Z","end":"2026-04-14T07:02:40.407361Z","steps":["trace[16360030] 'read index received' (duration: 118.70609ms)","trace[16360030] 'applied index is now lower than readState.Index' (duration: 551.703µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:40.407954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.771624ms","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-04-14T07:02:40.408091Z","caller":"traceutil/trace.go:171","msg":"trace[581852141] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21699; }","duration":"119.997279ms","start":"2026-04-14T07:02:40.288054Z","end":"2026-04-14T07:02:40.408052Z","steps":["trace[581852141] 'agreement among raft nodes before linearized reading' (duration: 119.551549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:40.621822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.791267ms","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-04-14T07:02:40.621905Z","caller":"traceutil/trace.go:171","msg":"trace[643609792] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:21701; }","duration":"135.888679ms","start":"2026-04-14T07:02:40.485999Z","end":"2026-04-14T07:02:40.621888Z","steps":["trace[643609792] 'range keys from in-memory index tree' (duration: 135.583762ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:48.060133Z","caller":"traceutil/trace.go:171","msg":"trace[1117425381] transaction","detail":"{read_only:false; response_revision:21765; number_of_response:1; }","duration":"279.828989ms","start":"2026-04-14T07:02:47.780267Z","end":"2026-04-14T07:02:48.060096Z","steps":["trace[1117425381] 'process raft request' (duration: 279.632915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:48.437156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.570885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:02:48.437284Z","caller":"traceutil/trace.go:171","msg":"trace[945785889] linearizableReadLoop","detail":"{readStateIndex:22409; appliedIndex:22408; }","duration":"598.715486ms","start":"2026-04-14T07:02:47.838552Z","end":"2026-04-14T07:02:48.437268Z","steps":["trace[945785889] 'read index received' (duration: 221.577292ms)","trace[945785889] 'applied index is now lower than readState.Index' (duration: 377.136873ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:48.437374Z","caller":"traceutil/trace.go:171","msg":"trace[510667068] transaction","detail":"{read_only:false; response_revision:21766; number_of_response:1; }","duration":"617.825105ms","start":"2026-04-14T07:02:47.81954Z","end":"2026-04-14T07:02:48.437365Z","steps":["trace[510667068] 'process raft request' (duration: 308.954463ms)","trace[510667068] 'compare' (duration: 307.659906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:48.437423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:47.819519Z","time spent":"617.881425ms","remote":"127.0.0.1:54620","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-04-14T07:02:48.437599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.045712ms","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":"info","ts":"2026-04-14T07:02:48.437633Z","caller":"traceutil/trace.go:171","msg":"trace[722266391] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:21766; }","duration":"599.100164ms","start":"2026-04-14T07:02:47.838519Z","end":"2026-04-14T07:02:48.437619Z","steps":["trace[722266391] 'agreement among raft nodes before linearized reading' (duration: 599.039983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:48.437655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:47.838507Z","time spent":"599.142035ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:02:48.437661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.283365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:02:48.437789Z","caller":"traceutil/trace.go:171","msg":"trace[1477137904] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:21766; }","duration":"256.441079ms","start":"2026-04-14T07:02:48.181326Z","end":"2026-04-14T07:02:48.437767Z","steps":["trace[1477137904] 'agreement among raft nodes before linearized reading' (duration: 256.180154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:48.899008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.041371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:02:48.89977Z","caller":"traceutil/trace.go:171","msg":"trace[306877969] transaction","detail":"{read_only:false; response_revision:21767; number_of_response:1; }","duration":"457.668887ms","start":"2026-04-14T07:02:48.442051Z","end":"2026-04-14T07:02:48.89972Z","steps":["trace[306877969] 'process raft request' (duration: 158.313909ms)","trace[306877969] 'compare' (duration: 297.55413ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:48.899814Z","caller":"traceutil/trace.go:171","msg":"trace[1608777825] linearizableReadLoop","detail":"{readStateIndex:22411; appliedIndex:22409; }","duration":"426.461678ms","start":"2026-04-14T07:02:48.473321Z","end":"2026-04-14T07:02:48.899782Z","steps":["trace[1608777825] 'read index received' (duration: 127.270863ms)","trace[1608777825] 'applied index is now lower than readState.Index' (duration: 299.188025ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:48.89982Z","caller":"traceutil/trace.go:171","msg":"trace[514784802] transaction","detail":"{read_only:false; response_revision:21768; number_of_response:1; }","duration":"446.593589ms","start":"2026-04-14T07:02:48.453167Z","end":"2026-04-14T07:02:48.89976Z","steps":["trace[514784802] 'process raft request' (duration: 446.418726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:48.900124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:48.442021Z","time spent":"457.833119ms","remote":"127.0.0.1:54620","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-04-14T07:02:48.901289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:48.453139Z","time spent":"447.966099ms","remote":"127.0.0.1:54620","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-04-14T07:02:49.09286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.118364ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-14T07:02:49.093469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.103973ms","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-04-14T07:02:49.093588Z","caller":"traceutil/trace.go:171","msg":"trace[1015905758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21768; }","duration":"620.278397ms","start":"2026-04-14T07:02:48.473277Z","end":"2026-04-14T07:02:49.093555Z","steps":["trace[1015905758] 'agreement among raft nodes before linearized reading' (duration: 426.591041ms)","trace[1015905758] 'range keys from in-memory index tree' (duration: 192.97809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:49.093638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:48.473249Z","time spent":"620.378689ms","remote":"127.0.0.1:54620","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-04-14T07:02:49.093773Z","caller":"traceutil/trace.go:171","msg":"trace[388843594] transaction","detail":"{read_only:false; response_revision:21769; number_of_response:1; }","duration":"441.689344ms","start":"2026-04-14T07:02:48.652042Z","end":"2026-04-14T07:02:49.093731Z","steps":["trace[388843594] 'process raft request' (duration: 440.970989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.093968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:48.652017Z","time spent":"441.836467ms","remote":"127.0.0.1:54620","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-04-14T07:02:49.128742Z","caller":"traceutil/trace.go:171","msg":"trace[205958286] transaction","detail":"{read_only:false; response_revision:21770; number_of_response:1; }","duration":"261.059578ms","start":"2026-04-14T07:02:48.867623Z","end":"2026-04-14T07:02:49.128682Z","steps":["trace[205958286] 'process raft request' (duration: 227.000769ms)","trace[205958286] 'compare' (duration: 33.727132ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:49.128751Z","caller":"traceutil/trace.go:171","msg":"trace[515421464] linearizableReadLoop","detail":"{readStateIndex:22414; appliedIndex:22413; }","duration":"228.842118ms","start":"2026-04-14T07:02:48.899868Z","end":"2026-04-14T07:02:49.12871Z","steps":["trace[515421464] 'read index received' (duration: 194.764729ms)","trace[515421464] 'applied index is now lower than readState.Index' (duration: 34.071559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:49.128922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.312894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T07:02:49.129023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.438192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-14T07:02:49.129146Z","caller":"traceutil/trace.go:171","msg":"trace[233019979] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:21771; }","duration":"372.632866ms","start":"2026-04-14T07:02:48.756484Z","end":"2026-04-14T07:02:49.129116Z","steps":["trace[233019979] 'agreement among raft nodes before linearized reading' (duration: 372.264978ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:49.129124Z","caller":"traceutil/trace.go:171","msg":"trace[555701253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21771; }","duration":"411.473187ms","start":"2026-04-14T07:02:48.717521Z","end":"2026-04-14T07:02:49.128995Z","steps":["trace[555701253] 'agreement among raft nodes before linearized reading' (duration: 411.284204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.129276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:48.717493Z","time spent":"411.765584ms","remote":"127.0.0.1:54322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T07:02:49.129251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.829814ms","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":"warn","ts":"2026-04-14T07:02:49.129288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.117141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-14T07:02:49.129207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:48.756456Z","time spent":"372.739969ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-04-14T07:02:49.129385Z","caller":"traceutil/trace.go:171","msg":"trace[1604194982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21771; }","duration":"177.267684ms","start":"2026-04-14T07:02:48.952096Z","end":"2026-04-14T07:02:49.129363Z","steps":["trace[1604194982] 'agreement among raft nodes before linearized reading' (duration: 176.972908ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:49.129385Z","caller":"traceutil/trace.go:171","msg":"trace[898105979] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:21771; }","duration":"162.039369ms","start":"2026-04-14T07:02:48.967304Z","end":"2026-04-14T07:02:49.129343Z","steps":["trace[898105979] 'agreement among raft nodes before linearized reading' (duration: 161.813754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.129637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.212211ms","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-04-14T07:02:49.129807Z","caller":"traceutil/trace.go:171","msg":"trace[670961955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21771; }","duration":"461.420186ms","start":"2026-04-14T07:02:48.668342Z","end":"2026-04-14T07:02:49.129762Z","steps":["trace[670961955] 'agreement among raft nodes before linearized reading' (duration: 460.478246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.129962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:48.668313Z","time spent":"461.63488ms","remote":"127.0.0.1:54620","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-04-14T07:02:49.605495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.062713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:02:49.605774Z","caller":"traceutil/trace.go:171","msg":"trace[1709443147] linearizableReadLoop","detail":"{readStateIndex:22419; appliedIndex:22415; }","duration":"372.856442ms","start":"2026-04-14T07:02:49.232896Z","end":"2026-04-14T07:02:49.605753Z","steps":["trace[1709443147] 'read index received' (duration: 65.460382ms)","trace[1709443147] 'applied index is now lower than readState.Index' (duration: 307.39468ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:49.605895Z","caller":"traceutil/trace.go:171","msg":"trace[1210842069] transaction","detail":"{read_only:false; response_revision:21773; number_of_response:1; }","duration":"470.045702ms","start":"2026-04-14T07:02:49.135805Z","end":"2026-04-14T07:02:49.605851Z","steps":["trace[1210842069] 'process raft request' (duration: 469.811037ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:49.60598Z","caller":"traceutil/trace.go:171","msg":"trace[1137661878] transaction","detail":"{read_only:false; response_revision:21774; number_of_response:1; }","duration":"469.810466ms","start":"2026-04-14T07:02:49.136162Z","end":"2026-04-14T07:02:49.605972Z","steps":["trace[1137661878] 'process raft request' (duration: 469.505929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.606051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.79127ms","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-04-14T07:02:49.606079Z","caller":"traceutil/trace.go:171","msg":"trace[353515106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21775; }","duration":"372.865152ms","start":"2026-04-14T07:02:49.233203Z","end":"2026-04-14T07:02:49.606069Z","steps":["trace[353515106] 'agreement among raft nodes before linearized reading' (duration: 372.80869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.606107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:49.233175Z","time spent":"372.924723ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T07:02:49.60611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:49.135771Z","time spent":"470.217976ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:02:49.606135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:49.136122Z","time spent":"469.909828ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:02:49.605992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.100747ms","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-04-14T07:02:49.60634Z","caller":"traceutil/trace.go:171","msg":"trace[1386164876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21775; }","duration":"373.481114ms","start":"2026-04-14T07:02:49.232849Z","end":"2026-04-14T07:02:49.606331Z","steps":["trace[1386164876] 'agreement among raft nodes before linearized reading' (duration: 373.002694ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:49.606506Z","caller":"traceutil/trace.go:171","msg":"trace[1939629267] transaction","detail":"{read_only:false; response_revision:21772; number_of_response:1; }","duration":"471.707807ms","start":"2026-04-14T07:02:49.134782Z","end":"2026-04-14T07:02:49.60649Z","steps":["trace[1939629267] 'process raft request' (duration: 163.551771ms)","trace[1939629267] 'compare' (duration: 306.92455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:49.606578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:49.134749Z","time spent":"471.789638ms","remote":"127.0.0.1:54620","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-04-14T07:02:49.605909Z","caller":"traceutil/trace.go:171","msg":"trace[1915420623] transaction","detail":"{read_only:false; response_revision:21775; number_of_response:1; }","duration":"378.129244ms","start":"2026-04-14T07:02:49.227739Z","end":"2026-04-14T07:02:49.605869Z","steps":["trace[1915420623] 'process raft request' (duration: 377.967211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.606696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:49.232835Z","time spent":"373.524836ms","remote":"127.0.0.1:54620","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-04-14T07:02:49.606696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:49.227713Z","time spent":"378.949923ms","remote":"127.0.0.1:54620","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-04-14T07:02:49.606723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.436626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-04-14T07:02:49.606756Z","caller":"traceutil/trace.go:171","msg":"trace[1662398775] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:21775; }","duration":"190.519229ms","start":"2026-04-14T07:02:49.416226Z","end":"2026-04-14T07:02:49.606746Z","steps":["trace[1662398775] 'agreement among raft nodes before linearized reading' (duration: 190.414957ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:49.864957Z","caller":"traceutil/trace.go:171","msg":"trace[1361245849] transaction","detail":"{read_only:false; response_revision:21776; number_of_response:1; }","duration":"244.830232ms","start":"2026-04-14T07:02:49.620087Z","end":"2026-04-14T07:02:49.864917Z","steps":["trace[1361245849] 'process raft request' (duration: 244.227969ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:49.883205Z","caller":"traceutil/trace.go:171","msg":"trace[1014965887] linearizableReadLoop","detail":"{readStateIndex:22421; appliedIndex:22419; }","duration":"259.147737ms","start":"2026-04-14T07:02:49.624032Z","end":"2026-04-14T07:02:49.88318Z","steps":["trace[1014965887] 'read index received' (duration: 240.279854ms)","trace[1014965887] 'applied index is now lower than readState.Index' (duration: 18.866723ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:49.883279Z","caller":"traceutil/trace.go:171","msg":"trace[87643416] transaction","detail":"{read_only:false; response_revision:21777; number_of_response:1; }","duration":"263.00423ms","start":"2026-04-14T07:02:49.620218Z","end":"2026-04-14T07:02:49.883222Z","steps":["trace[87643416] 'process raft request' (duration: 262.726464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:49.883376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.31637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:02:49.883421Z","caller":"traceutil/trace.go:171","msg":"trace[1275130573] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:21778; }","duration":"259.387522ms","start":"2026-04-14T07:02:49.624017Z","end":"2026-04-14T07:02:49.883404Z","steps":["trace[1275130573] 'agreement among raft nodes before linearized reading' (duration: 259.2816ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:49.883536Z","caller":"traceutil/trace.go:171","msg":"trace[1575268359] transaction","detail":"{read_only:false; response_revision:21778; number_of_response:1; }","duration":"176.880456ms","start":"2026-04-14T07:02:49.706391Z","end":"2026-04-14T07:02:49.883271Z","steps":["trace[1575268359] 'process raft request' (duration: 176.725403ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:50.196149Z","caller":"traceutil/trace.go:171","msg":"trace[1481590271] transaction","detail":"{read_only:false; response_revision:21779; number_of_response:1; }","duration":"127.859958ms","start":"2026-04-14T07:02:50.068243Z","end":"2026-04-14T07:02:50.196103Z","steps":["trace[1481590271] 'process raft request' (duration: 127.555101ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:50.662605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18421} {"level":"warn","ts":"2026-04-14T07:02:50.663837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.315657ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-04-14T07:02:50.664003Z","caller":"traceutil/trace.go:171","msg":"trace[933406887] compact","detail":"{revision:18421; response_revision:21780; }","duration":"279.917452ms","start":"2026-04-14T07:02:50.384064Z","end":"2026-04-14T07:02:50.663982Z","steps":["trace[933406887] 'process raft request' (duration: 135.417058ms)","trace[933406887] 'check and update compact revision' (duration: 142.160514ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:50.664812Z","caller":"traceutil/trace.go:171","msg":"trace[670685351] linearizableReadLoop","detail":"{readStateIndex:22426; appliedIndex:22424; }","duration":"218.706761ms","start":"2026-04-14T07:02:50.44608Z","end":"2026-04-14T07:02:50.664787Z","steps":["trace[670685351] 'read index received' (duration: 73.521883ms)","trace[670685351] 'applied index is now lower than readState.Index' (duration: 145.183848ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:50.66487Z","caller":"traceutil/trace.go:171","msg":"trace[449418988] transaction","detail":"{read_only:false; response_revision:21781; number_of_response:1; }","duration":"221.325337ms","start":"2026-04-14T07:02:50.443528Z","end":"2026-04-14T07:02:50.664853Z","steps":["trace[449418988] 'process raft request' (duration: 220.426858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:50.665028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.927996ms","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-04-14T07:02:50.665078Z","caller":"traceutil/trace.go:171","msg":"trace[128279481] 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:21781; }","duration":"219.018648ms","start":"2026-04-14T07:02:50.446045Z","end":"2026-04-14T07:02:50.665064Z","steps":["trace[128279481] 'agreement among raft nodes before linearized reading' (duration: 218.837274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:50.849522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.631922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:02:50.850054Z","caller":"traceutil/trace.go:171","msg":"trace[715956887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21781; }","duration":"135.225615ms","start":"2026-04-14T07:02:50.714795Z","end":"2026-04-14T07:02:50.850021Z","steps":["trace[715956887] 'range keys from in-memory index tree' (duration: 134.448858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:50.849704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.016953ms","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-04-14T07:02:50.850783Z","caller":"traceutil/trace.go:171","msg":"trace[2033292341] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21781; }","duration":"103.156878ms","start":"2026-04-14T07:02:50.747598Z","end":"2026-04-14T07:02:50.850755Z","steps":["trace[2033292341] 'range keys from in-memory index tree' (duration: 101.881881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:50.992265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.207477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18411"} {"level":"info","ts":"2026-04-14T07:02:50.992357Z","caller":"traceutil/trace.go:171","msg":"trace[457381857] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21781; }","duration":"130.334309ms","start":"2026-04-14T07:02:50.862002Z","end":"2026-04-14T07:02:50.992336Z","steps":["trace[457381857] 'range keys from in-memory index tree' (duration: 129.967691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:50.99252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.829656ms","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":"info","ts":"2026-04-14T07:02:50.992617Z","caller":"traceutil/trace.go:171","msg":"trace[2026796169] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:21781; }","duration":"127.982709ms","start":"2026-04-14T07:02:50.864611Z","end":"2026-04-14T07:02:50.992594Z","steps":["trace[2026796169] 'range keys from in-memory index tree' (duration: 127.771134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:51.269842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.530959ms","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-04-14T07:02:51.269957Z","caller":"traceutil/trace.go:171","msg":"trace[1850152219] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21783; }","duration":"262.685194ms","start":"2026-04-14T07:02:51.007246Z","end":"2026-04-14T07:02:51.269931Z","steps":["trace[1850152219] 'range keys from in-memory index tree' (duration: 262.336215ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:51.283882Z","caller":"traceutil/trace.go:171","msg":"trace[629156448] transaction","detail":"{read_only:false; response_revision:21784; number_of_response:1; }","duration":"184.250484ms","start":"2026-04-14T07:02:51.099568Z","end":"2026-04-14T07:02:51.283819Z","steps":["trace[629156448] 'process raft request' (duration: 168.695431ms)","trace[629156448] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; req_size:604; } (duration: 13.916428ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:51.283989Z","caller":"traceutil/trace.go:171","msg":"trace[1477485145] transaction","detail":"{read_only:false; response_revision:21785; number_of_response:1; }","duration":"148.52205ms","start":"2026-04-14T07:02:51.135436Z","end":"2026-04-14T07:02:51.283958Z","steps":["trace[1477485145] 'process raft request' (duration: 148.385197ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:51.283988Z","caller":"traceutil/trace.go:171","msg":"trace[1484889994] linearizableReadLoop","detail":"{readStateIndex:22429; appliedIndex:22428; }","duration":"151.307249ms","start":"2026-04-14T07:02:51.132637Z","end":"2026-04-14T07:02:51.283944Z","steps":["trace[1484889994] 'read index received' (duration: 135.597543ms)","trace[1484889994] 'applied index is now lower than readState.Index' (duration: 15.705125ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:51.284336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.690637ms","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-04-14T07:02:51.284427Z","caller":"traceutil/trace.go:171","msg":"trace[1150830318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21785; }","duration":"151.84158ms","start":"2026-04-14T07:02:51.132571Z","end":"2026-04-14T07:02:51.284412Z","steps":["trace[1150830318] 'agreement among raft nodes before linearized reading' (duration: 151.516814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:51.766912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.4238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:02:51.767008Z","caller":"traceutil/trace.go:171","msg":"trace[273226717] linearizableReadLoop","detail":"{readStateIndex:22431; appliedIndex:22430; }","duration":"445.035787ms","start":"2026-04-14T07:02:51.321958Z","end":"2026-04-14T07:02:51.766993Z","steps":["trace[273226717] 'read index received' (duration: 63.952µs)","trace[273226717] 'applied index is now lower than readState.Index' (duration: 444.970365ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:51.767073Z","caller":"traceutil/trace.go:171","msg":"trace[1593887221] transaction","detail":"{read_only:false; response_revision:21786; number_of_response:1; }","duration":"478.017212ms","start":"2026-04-14T07:02:51.28904Z","end":"2026-04-14T07:02:51.767057Z","steps":["trace[1593887221] 'process raft request' (duration: 28.364677ms)","trace[1593887221] 'compare' (duration: 282.862995ms)","trace[1593887221] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:486; } (duration: 166.100525ms)"],"step_count":3} {"level":"warn","ts":"2026-04-14T07:02:51.767121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:51.289014Z","time spent":"478.078744ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:02:51.76717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.19147ms","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-04-14T07:02:51.767247Z","caller":"traceutil/trace.go:171","msg":"trace[1129851288] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:21786; }","duration":"445.295202ms","start":"2026-04-14T07:02:51.321932Z","end":"2026-04-14T07:02:51.767227Z","steps":["trace[1129851288] 'agreement among raft nodes before linearized reading' (duration: 445.151619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:51.767278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:51.321869Z","time spent":"445.402424ms","remote":"127.0.0.1:54732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:02:51.819954Z","caller":"traceutil/trace.go:171","msg":"trace[1004090095] transaction","detail":"{read_only:false; response_revision:21787; number_of_response:1; }","duration":"206.47655ms","start":"2026-04-14T07:02:51.613461Z","end":"2026-04-14T07:02:51.819938Z","steps":["trace[1004090095] 'process raft request' (duration: 200.807789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:51.820151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.029634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-04-14T07:02:51.820179Z","caller":"traceutil/trace.go:171","msg":"trace[1790260555] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:21788; }","duration":"344.102786ms","start":"2026-04-14T07:02:51.476061Z","end":"2026-04-14T07:02:51.820164Z","steps":["trace[1790260555] 'agreement among raft nodes before linearized reading' (duration: 343.997744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:51.820196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:02:51.47604Z","time spent":"344.151027ms","remote":"127.0.0.1:54518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-04-14T07:02:51.820348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.334991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:02:51.820362Z","caller":"traceutil/trace.go:171","msg":"trace[474302807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21788; }","duration":"102.386372ms","start":"2026-04-14T07:02:51.71797Z","end":"2026-04-14T07:02:51.820357Z","steps":["trace[474302807] 'agreement among raft nodes before linearized reading' (duration: 102.358872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:51.820483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.116304ms","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-04-14T07:02:51.820496Z","caller":"traceutil/trace.go:171","msg":"trace[1555975792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21788; }","duration":"207.182965ms","start":"2026-04-14T07:02:51.613309Z","end":"2026-04-14T07:02:51.820492Z","steps":["trace[1555975792] 'agreement among raft nodes before linearized reading' (duration: 207.127374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:51.820592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.292487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:02:51.820605Z","caller":"traceutil/trace.go:171","msg":"trace[823391766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21788; }","duration":"207.343108ms","start":"2026-04-14T07:02:51.613258Z","end":"2026-04-14T07:02:51.820601Z","steps":["trace[823391766] 'agreement among raft nodes before linearized reading' (duration: 207.301067ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:51.882901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18421,"took":"1.2127877s","hash":366736679,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":42946560,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-04-14T07:02:51.882972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":366736679,"revision":18421,"compact-revision":15735} {"level":"info","ts":"2026-04-14T07:02:59.273968Z","caller":"traceutil/trace.go:171","msg":"trace[517762984] linearizableReadLoop","detail":"{readStateIndex:22490; appliedIndex:22489; }","duration":"185.806536ms","start":"2026-04-14T07:02:59.088124Z","end":"2026-04-14T07:02:59.273931Z","steps":["trace[517762984] 'read index received' (duration: 130.36378ms)","trace[517762984] 'applied index is now lower than readState.Index' (duration: 55.440976ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:02:59.27416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.999621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:02:59.274153Z","caller":"traceutil/trace.go:171","msg":"trace[1215237537] transaction","detail":"{read_only:false; response_revision:21843; number_of_response:1; }","duration":"240.210511ms","start":"2026-04-14T07:02:59.033911Z","end":"2026-04-14T07:02:59.274121Z","steps":["trace[1215237537] 'process raft request' (duration: 184.613471ms)","trace[1215237537] 'compare' (duration: 54.979667ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:59.274229Z","caller":"traceutil/trace.go:171","msg":"trace[127123094] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:21843; }","duration":"186.134234ms","start":"2026-04-14T07:02:59.088084Z","end":"2026-04-14T07:02:59.274218Z","steps":["trace[127123094] 'agreement among raft nodes before linearized reading' (duration: 185.97374ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:59.532283Z","caller":"traceutil/trace.go:171","msg":"trace[642220508] linearizableReadLoop","detail":"{readStateIndex:22492; appliedIndex:22490; }","duration":"184.685573ms","start":"2026-04-14T07:02:59.347569Z","end":"2026-04-14T07:02:59.532255Z","steps":["trace[642220508] 'read index received' (duration: 184.314635ms)","trace[642220508] 'applied index is now lower than readState.Index' (duration: 369.638µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:02:59.532336Z","caller":"traceutil/trace.go:171","msg":"trace[1140420279] transaction","detail":"{read_only:false; response_revision:21844; number_of_response:1; }","duration":"209.063886ms","start":"2026-04-14T07:02:59.32321Z","end":"2026-04-14T07:02:59.532274Z","steps":["trace[1140420279] 'process raft request' (duration: 208.768699ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:02:59.532378Z","caller":"traceutil/trace.go:171","msg":"trace[1214607025] transaction","detail":"{read_only:false; response_revision:21845; number_of_response:1; }","duration":"209.049215ms","start":"2026-04-14T07:02:59.323266Z","end":"2026-04-14T07:02:59.532315Z","steps":["trace[1214607025] 'process raft request' (duration: 208.936253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:02:59.53253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.943059ms","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-04-14T07:02:59.532583Z","caller":"traceutil/trace.go:171","msg":"trace[67324741] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:21845; }","duration":"185.090852ms","start":"2026-04-14T07:02:59.347477Z","end":"2026-04-14T07:02:59.532568Z","steps":["trace[67324741] 'agreement among raft nodes before linearized reading' (duration: 184.945539ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:01.191154Z","caller":"traceutil/trace.go:171","msg":"trace[1215020430] transaction","detail":"{read_only:false; response_revision:21855; number_of_response:1; }","duration":"108.077834ms","start":"2026-04-14T07:03:01.083047Z","end":"2026-04-14T07:03:01.191125Z","steps":["trace[1215020430] 'process raft request' (duration: 107.915191ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:01.458095Z","caller":"traceutil/trace.go:171","msg":"trace[1724788873] linearizableReadLoop","detail":"{readStateIndex:22503; appliedIndex:22502; }","duration":"149.755055ms","start":"2026-04-14T07:03:01.308318Z","end":"2026-04-14T07:03:01.458073Z","steps":["trace[1724788873] 'read index received' (duration: 149.564451ms)","trace[1724788873] 'applied index is now lower than readState.Index' (duration: 189.924µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:01.458185Z","caller":"traceutil/trace.go:171","msg":"trace[1908545240] transaction","detail":"{read_only:false; response_revision:21856; number_of_response:1; }","duration":"178.162743ms","start":"2026-04-14T07:03:01.280015Z","end":"2026-04-14T07:03:01.458178Z","steps":["trace[1908545240] 'process raft request' (duration: 177.931648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:01.458449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.106902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-04-14T07:03:01.458485Z","caller":"traceutil/trace.go:171","msg":"trace[1212214392] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:21856; }","duration":"150.193424ms","start":"2026-04-14T07:03:01.308284Z","end":"2026-04-14T07:03:01.458478Z","steps":["trace[1212214392] 'agreement among raft nodes before linearized reading' (duration: 150.062012ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:01.622529Z","caller":"traceutil/trace.go:171","msg":"trace[1631989084] transaction","detail":"{read_only:false; response_revision:21857; number_of_response:1; }","duration":"156.95593ms","start":"2026-04-14T07:03:01.46555Z","end":"2026-04-14T07:03:01.622506Z","steps":["trace[1631989084] 'process raft request' (duration: 75.702541ms)","trace[1631989084] 'compare' (duration: 80.013142ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:02.054016Z","caller":"traceutil/trace.go:171","msg":"trace[1957892214] transaction","detail":"{read_only:false; response_revision:21861; number_of_response:1; }","duration":"168.161799ms","start":"2026-04-14T07:03:01.885833Z","end":"2026-04-14T07:03:02.053995Z","steps":["trace[1957892214] 'process raft request' (duration: 167.929244ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:02.096311Z","caller":"traceutil/trace.go:171","msg":"trace[445079481] linearizableReadLoop","detail":"{readStateIndex:22509; appliedIndex:22507; }","duration":"203.792873ms","start":"2026-04-14T07:03:01.89248Z","end":"2026-04-14T07:03:02.096273Z","steps":["trace[445079481] 'read index received' (duration: 161.377335ms)","trace[445079481] 'applied index is now lower than readState.Index' (duration: 42.413868ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:02.096424Z","caller":"traceutil/trace.go:171","msg":"trace[1247781985] transaction","detail":"{read_only:false; response_revision:21862; number_of_response:1; }","duration":"207.402289ms","start":"2026-04-14T07:03:01.888968Z","end":"2026-04-14T07:03:02.096371Z","steps":["trace[1247781985] 'process raft request' (duration: 207.034581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:02.096606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.097959ms","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-04-14T07:03:02.096655Z","caller":"traceutil/trace.go:171","msg":"trace[1453253382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21863; }","duration":"204.205912ms","start":"2026-04-14T07:03:01.892434Z","end":"2026-04-14T07:03:02.096639Z","steps":["trace[1453253382] 'agreement among raft nodes before linearized reading' (duration: 204.014148ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:02.096606Z","caller":"traceutil/trace.go:171","msg":"trace[534884863] transaction","detail":"{read_only:false; response_revision:21863; number_of_response:1; }","duration":"140.14265ms","start":"2026-04-14T07:03:01.956433Z","end":"2026-04-14T07:03:02.096576Z","steps":["trace[534884863] 'process raft request' (duration: 139.771462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:02.096716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.495554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:03:02.096831Z","caller":"traceutil/trace.go:171","msg":"trace[1449539135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21863; }","duration":"159.646068ms","start":"2026-04-14T07:03:01.937166Z","end":"2026-04-14T07:03:02.096812Z","steps":["trace[1449539135] 'agreement among raft nodes before linearized reading' (duration: 159.332251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:02.096738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.56243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T07:03:02.09694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.050761ms","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-04-14T07:03:02.09697Z","caller":"traceutil/trace.go:171","msg":"trace[1770094120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21863; }","duration":"134.921478ms","start":"2026-04-14T07:03:01.962022Z","end":"2026-04-14T07:03:02.096944Z","steps":["trace[1770094120] 'agreement among raft nodes before linearized reading' (duration: 134.50845ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:02.097008Z","caller":"traceutil/trace.go:171","msg":"trace[1005670845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21863; }","duration":"135.116412ms","start":"2026-04-14T07:03:01.961866Z","end":"2026-04-14T07:03:02.096982Z","steps":["trace[1005670845] 'agreement among raft nodes before linearized reading' (duration: 134.972589ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:02.247074Z","caller":"traceutil/trace.go:171","msg":"trace[32748937] transaction","detail":"{read_only:false; response_revision:21864; number_of_response:1; }","duration":"145.812892ms","start":"2026-04-14T07:03:02.10123Z","end":"2026-04-14T07:03:02.247043Z","steps":["trace[32748937] 'process raft request' (duration: 139.831694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:02.603575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.389394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:03:02.603868Z","caller":"traceutil/trace.go:171","msg":"trace[407592499] transaction","detail":"{read_only:false; response_revision:21865; number_of_response:1; }","duration":"501.659288ms","start":"2026-04-14T07:03:02.102171Z","end":"2026-04-14T07:03:02.603831Z","steps":["trace[407592499] 'process raft request' (duration: 311.884786ms)","trace[407592499] 'compare' (duration: 189.19468ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:02.604051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:02.102149Z","time spent":"501.795211ms","remote":"127.0.0.1:54620","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-04-14T07:03:02.604053Z","caller":"traceutil/trace.go:171","msg":"trace[1958473855] transaction","detail":"{read_only:false; response_revision:21867; number_of_response:1; }","duration":"500.905483ms","start":"2026-04-14T07:03:02.103131Z","end":"2026-04-14T07:03:02.604036Z","steps":["trace[1958473855] 'process raft request' (duration: 500.709009ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:02.604098Z","caller":"traceutil/trace.go:171","msg":"trace[2040567425] transaction","detail":"{read_only:false; response_revision:21869; number_of_response:1; }","duration":"305.50893ms","start":"2026-04-14T07:03:02.298531Z","end":"2026-04-14T07:03:02.604039Z","steps":["trace[2040567425] 'process raft request' (duration: 305.456949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:02.604157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:02.103121Z","time spent":"500.984944ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:03:02.604197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:02.298501Z","time spent":"305.654262ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:03:02.604236Z","caller":"traceutil/trace.go:171","msg":"trace[317698555] transaction","detail":"{read_only:false; response_revision:21868; number_of_response:1; }","duration":"354.366245ms","start":"2026-04-14T07:03:02.249851Z","end":"2026-04-14T07:03:02.604217Z","steps":["trace[317698555] 'process raft request' (duration: 354.089379ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:02.60426Z","caller":"traceutil/trace.go:171","msg":"trace[1278726571] linearizableReadLoop","detail":"{readStateIndex:22514; appliedIndex:22510; }","duration":"443.006822ms","start":"2026-04-14T07:03:02.161205Z","end":"2026-04-14T07:03:02.604211Z","steps":["trace[1278726571] 'read index received' (duration: 79.88071ms)","trace[1278726571] 'applied index is now lower than readState.Index' (duration: 363.115802ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:02.604318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:02.249828Z","time spent":"354.445547ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:03:02.604375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.149406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:03:02.604437Z","caller":"traceutil/trace.go:171","msg":"trace[1329873868] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:21869; }","duration":"443.304789ms","start":"2026-04-14T07:03:02.161117Z","end":"2026-04-14T07:03:02.604422Z","steps":["trace[1329873868] 'agreement among raft nodes before linearized reading' (duration: 443.186636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:02.604488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:02.161093Z","time spent":"443.386851ms","remote":"127.0.0.1:44520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"info","ts":"2026-04-14T07:03:02.604412Z","caller":"traceutil/trace.go:171","msg":"trace[811325320] transaction","detail":"{read_only:false; response_revision:21866; number_of_response:1; }","duration":"501.922834ms","start":"2026-04-14T07:03:02.102172Z","end":"2026-04-14T07:03:02.604095Z","steps":["trace[811325320] 'process raft request' (duration: 501.539786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:02.605011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:02.10215Z","time spent":"502.525656ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:03:05.409716Z","caller":"traceutil/trace.go:171","msg":"trace[422388300] transaction","detail":"{read_only:false; response_revision:21892; number_of_response:1; }","duration":"205.441458ms","start":"2026-04-14T07:03:05.204249Z","end":"2026-04-14T07:03:05.409691Z","steps":["trace[422388300] 'process raft request' (duration: 205.310245ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:06.912205Z","caller":"traceutil/trace.go:171","msg":"trace[1132993885] transaction","detail":"{read_only:false; response_revision:21904; number_of_response:1; }","duration":"101.81837ms","start":"2026-04-14T07:03:06.80952Z","end":"2026-04-14T07:03:06.911338Z","steps":["trace[1132993885] 'process raft request' (duration: 101.568754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:16.9966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.877384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:03:16.996721Z","caller":"traceutil/trace.go:171","msg":"trace[2038952577] linearizableReadLoop","detail":"{readStateIndex:22628; appliedIndex:22626; }","duration":"325.466777ms","start":"2026-04-14T07:03:16.671234Z","end":"2026-04-14T07:03:16.996701Z","steps":["trace[2038952577] 'read index received' (duration: 54.308542ms)","trace[2038952577] 'applied index is now lower than readState.Index' (duration: 271.156975ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:16.996802Z","caller":"traceutil/trace.go:171","msg":"trace[1811382689] transaction","detail":"{read_only:false; response_revision:21978; number_of_response:1; }","duration":"344.250109ms","start":"2026-04-14T07:03:16.652539Z","end":"2026-04-14T07:03:16.996789Z","steps":["trace[1811382689] 'process raft request' (duration: 197.086768ms)","trace[1811382689] 'compare' (duration: 146.216931ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:16.996911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:16.652505Z","time spent":"344.325101ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T07:03:16.997071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.845934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-14T07:03:16.997131Z","caller":"traceutil/trace.go:171","msg":"trace[681552628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21978; }","duration":"325.970547ms","start":"2026-04-14T07:03:16.671143Z","end":"2026-04-14T07:03:16.997113Z","steps":["trace[681552628] 'agreement among raft nodes before linearized reading' (duration: 325.658841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:16.997181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:16.67111Z","time spent":"326.054179ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T07:03:17.058693Z","caller":"traceutil/trace.go:171","msg":"trace[1056178635] transaction","detail":"{read_only:false; response_revision:21979; number_of_response:1; }","duration":"133.368155ms","start":"2026-04-14T07:03:16.92529Z","end":"2026-04-14T07:03:17.058658Z","steps":["trace[1056178635] 'process raft request' (duration: 133.192461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:17.352747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.622684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:03:17.353049Z","caller":"traceutil/trace.go:171","msg":"trace[255479700] linearizableReadLoop","detail":"{readStateIndex:22630; appliedIndex:22629; }","duration":"356.277826ms","start":"2026-04-14T07:03:16.996748Z","end":"2026-04-14T07:03:17.353026Z","steps":["trace[255479700] 'read index received' (duration: 61.927895ms)","trace[255479700] 'applied index is now lower than readState.Index' (duration: 294.348741ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:17.353233Z","caller":"traceutil/trace.go:171","msg":"trace[378898313] transaction","detail":"{read_only:false; response_revision:21980; number_of_response:1; }","duration":"406.628574ms","start":"2026-04-14T07:03:16.946587Z","end":"2026-04-14T07:03:17.353216Z","steps":["trace[378898313] 'process raft request' (duration: 262.403907ms)","trace[378898313] 'compare' (duration: 143.459801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:17.353352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:16.946567Z","time spent":"406.717655ms","remote":"127.0.0.1:54620","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-04-14T07:03:17.3535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.630195ms","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-04-14T07:03:17.353599Z","caller":"traceutil/trace.go:171","msg":"trace[1464738596] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21981; }","duration":"670.805708ms","start":"2026-04-14T07:03:16.682767Z","end":"2026-04-14T07:03:17.353573Z","steps":["trace[1464738596] 'agreement among raft nodes before linearized reading' (duration: 670.479772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:17.353645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:16.68273Z","time spent":"670.906771ms","remote":"127.0.0.1:54620","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-04-14T07:03:17.353854Z","caller":"traceutil/trace.go:171","msg":"trace[1930710985] transaction","detail":"{read_only:false; response_revision:21981; number_of_response:1; }","duration":"351.109315ms","start":"2026-04-14T07:03:17.00269Z","end":"2026-04-14T07:03:17.353799Z","steps":["trace[1930710985] 'process raft request' (duration: 350.205676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:17.353954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.068069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T07:03:17.353932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.872873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:03:17.354234Z","caller":"traceutil/trace.go:171","msg":"trace[1989549743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21981; }","duration":"682.312665ms","start":"2026-04-14T07:03:16.671868Z","end":"2026-04-14T07:03:17.35418Z","steps":["trace[1989549743] 'agreement among raft nodes before linearized reading' (duration: 681.904356ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:17.354247Z","caller":"traceutil/trace.go:171","msg":"trace[1016351275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21981; }","duration":"638.240342ms","start":"2026-04-14T07:03:16.715981Z","end":"2026-04-14T07:03:17.354222Z","steps":["trace[1016351275] 'agreement among raft nodes before linearized reading' (duration: 637.822762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:17.353862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.490103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-04-14T07:03:17.354296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:16.715959Z","time spent":"638.327533ms","remote":"127.0.0.1:54322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T07:03:17.354298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:16.671832Z","time spent":"682.451588ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T07:03:17.354342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:17.002654Z","time spent":"351.505955ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:03:17.35435Z","caller":"traceutil/trace.go:171","msg":"trace[1285045546] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21981; }","duration":"230.029514ms","start":"2026-04-14T07:03:17.124294Z","end":"2026-04-14T07:03:17.354324Z","steps":["trace[1285045546] 'agreement among raft nodes before linearized reading' (duration: 229.359699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:17.695745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.761535ms","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-04-14T07:03:17.695873Z","caller":"traceutil/trace.go:171","msg":"trace[141930917] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21983; }","duration":"177.954609ms","start":"2026-04-14T07:03:17.517884Z","end":"2026-04-14T07:03:17.695838Z","steps":["trace[141930917] 'range keys from in-memory index tree' (duration: 177.573741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:17.696182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.55162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:03:17.696301Z","caller":"traceutil/trace.go:171","msg":"trace[1519097760] transaction","detail":"{read_only:false; response_revision:21984; number_of_response:1; }","duration":"154.622561ms","start":"2026-04-14T07:03:17.54166Z","end":"2026-04-14T07:03:17.696283Z","steps":["trace[1519097760] 'process raft request' (duration: 33.857516ms)","trace[1519097760] 'compare' (duration: 120.404058ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:17.714019Z","caller":"traceutil/trace.go:171","msg":"trace[1842287488] transaction","detail":"{read_only:false; response_revision:21985; number_of_response:1; }","duration":"129.735467ms","start":"2026-04-14T07:03:17.584262Z","end":"2026-04-14T07:03:17.713998Z","steps":["trace[1842287488] 'process raft request' (duration: 129.585014ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:17.844799Z","caller":"traceutil/trace.go:171","msg":"trace[514728846] transaction","detail":"{read_only:false; response_revision:21986; number_of_response:1; }","duration":"225.774893ms","start":"2026-04-14T07:03:17.61899Z","end":"2026-04-14T07:03:17.844765Z","steps":["trace[514728846] 'process raft request' (duration: 225.398386ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:17.844955Z","caller":"traceutil/trace.go:171","msg":"trace[1564450943] linearizableReadLoop","detail":"{readStateIndex:22637; appliedIndex:22635; }","duration":"141.898718ms","start":"2026-04-14T07:03:17.703012Z","end":"2026-04-14T07:03:17.844911Z","steps":["trace[1564450943] 'read index received' (duration: 10.996485ms)","trace[1564450943] 'applied index is now lower than readState.Index' (duration: 130.893862ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:17.845102Z","caller":"traceutil/trace.go:171","msg":"trace[425022638] transaction","detail":"{read_only:false; response_revision:21987; number_of_response:1; }","duration":"217.142808ms","start":"2026-04-14T07:03:17.627945Z","end":"2026-04-14T07:03:17.845088Z","steps":["trace[425022638] 'process raft request' (duration: 216.76867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:17.845153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.113132ms","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-04-14T07:03:17.845195Z","caller":"traceutil/trace.go:171","msg":"trace[1189708520] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21987; }","duration":"142.216704ms","start":"2026-04-14T07:03:17.702963Z","end":"2026-04-14T07:03:17.84518Z","steps":["trace[1189708520] 'agreement among raft nodes before linearized reading' (duration: 142.094852ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:17.846663Z","caller":"traceutil/trace.go:171","msg":"trace[1064098726] transaction","detail":"{read_only:false; response_revision:21988; number_of_response:1; }","duration":"119.877235ms","start":"2026-04-14T07:03:17.726762Z","end":"2026-04-14T07:03:17.84664Z","steps":["trace[1064098726] 'process raft request' (duration: 119.670611ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:17.846851Z","caller":"traceutil/trace.go:171","msg":"trace[1188008446] transaction","detail":"{read_only:false; response_revision:21989; number_of_response:1; }","duration":"110.113517ms","start":"2026-04-14T07:03:17.736703Z","end":"2026-04-14T07:03:17.846816Z","steps":["trace[1188008446] 'process raft request' (duration: 109.894022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:18.798929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.941533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T07:03:20.262159Z","caller":"traceutil/trace.go:171","msg":"trace[1556038559] transaction","detail":"{read_only:false; response_revision:22004; number_of_response:1; }","duration":"112.587941ms","start":"2026-04-14T07:03:20.149539Z","end":"2026-04-14T07:03:20.262127Z","steps":["trace[1556038559] 'process raft request' (duration: 112.351415ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:20.416966Z","caller":"traceutil/trace.go:171","msg":"trace[1477053700] transaction","detail":"{read_only:false; response_revision:22006; number_of_response:1; }","duration":"133.926207ms","start":"2026-04-14T07:03:20.282991Z","end":"2026-04-14T07:03:20.416917Z","steps":["trace[1477053700] 'process raft request' (duration: 133.388425ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:23.88163Z","caller":"traceutil/trace.go:171","msg":"trace[1690801417] linearizableReadLoop","detail":"{readStateIndex:22681; appliedIndex:22679; }","duration":"115.771389ms","start":"2026-04-14T07:03:23.765824Z","end":"2026-04-14T07:03:23.881595Z","steps":["trace[1690801417] 'read index received' (duration: 29.965851ms)","trace[1690801417] 'applied index is now lower than readState.Index' (duration: 85.803758ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:23.881809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.944612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T07:03:23.881861Z","caller":"traceutil/trace.go:171","msg":"trace[2059909309] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:22029; }","duration":"116.046025ms","start":"2026-04-14T07:03:23.765797Z","end":"2026-04-14T07:03:23.881843Z","steps":["trace[2059909309] 'agreement among raft nodes before linearized reading' (duration: 115.921832ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:23.881831Z","caller":"traceutil/trace.go:171","msg":"trace[2140662573] transaction","detail":"{read_only:false; response_revision:22029; number_of_response:1; }","duration":"146.594468ms","start":"2026-04-14T07:03:23.735167Z","end":"2026-04-14T07:03:23.881762Z","steps":["trace[2140662573] 'process raft request' (duration: 131.338121ms)","trace[2140662573] 'compare' (duration: 14.91917ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:25.586395Z","caller":"traceutil/trace.go:171","msg":"trace[161630778] linearizableReadLoop","detail":"{readStateIndex:22694; appliedIndex:22692; }","duration":"127.909449ms","start":"2026-04-14T07:03:25.458458Z","end":"2026-04-14T07:03:25.586367Z","steps":["trace[161630778] 'read index received' (duration: 31.276561ms)","trace[161630778] 'applied index is now lower than readState.Index' (duration: 96.631848ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:25.58655Z","caller":"traceutil/trace.go:171","msg":"trace[510456286] transaction","detail":"{read_only:false; response_revision:22041; number_of_response:1; }","duration":"152.664667ms","start":"2026-04-14T07:03:25.433829Z","end":"2026-04-14T07:03:25.586494Z","steps":["trace[510456286] 'process raft request' (duration: 152.261169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:25.586722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.233195ms","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-04-14T07:03:25.589413Z","caller":"traceutil/trace.go:171","msg":"trace[1324830192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22041; }","duration":"130.989764ms","start":"2026-04-14T07:03:25.458392Z","end":"2026-04-14T07:03:25.589381Z","steps":["trace[1324830192] 'agreement among raft nodes before linearized reading' (duration: 128.130513ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:25.661875Z","caller":"traceutil/trace.go:171","msg":"trace[245895913] transaction","detail":"{read_only:false; response_revision:22042; number_of_response:1; }","duration":"171.082512ms","start":"2026-04-14T07:03:25.490741Z","end":"2026-04-14T07:03:25.661823Z","steps":["trace[245895913] 'process raft request' (duration: 170.668083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:25.661953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.037769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T07:03:25.662221Z","caller":"traceutil/trace.go:171","msg":"trace[116960344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22042; }","duration":"176.234733ms","start":"2026-04-14T07:03:25.485809Z","end":"2026-04-14T07:03:25.662044Z","steps":["trace[116960344] 'agreement among raft nodes before linearized reading' (duration: 175.875525ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:25.77627Z","caller":"traceutil/trace.go:171","msg":"trace[624035340] transaction","detail":"{read_only:false; response_revision:22043; number_of_response:1; }","duration":"180.984564ms","start":"2026-04-14T07:03:25.595258Z","end":"2026-04-14T07:03:25.776243Z","steps":["trace[624035340] 'process raft request' (duration: 180.78118ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:25.785993Z","caller":"traceutil/trace.go:171","msg":"trace[40045468] transaction","detail":"{read_only:false; response_revision:22044; number_of_response:1; }","duration":"119.580439ms","start":"2026-04-14T07:03:25.666382Z","end":"2026-04-14T07:03:25.785962Z","steps":["trace[40045468] 'process raft request' (duration: 119.409185ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:26.026188Z","caller":"traceutil/trace.go:171","msg":"trace[36523964] transaction","detail":"{read_only:false; response_revision:22048; number_of_response:1; }","duration":"124.74143ms","start":"2026-04-14T07:03:25.901417Z","end":"2026-04-14T07:03:26.026158Z","steps":["trace[36523964] 'process raft request' (duration: 124.686659ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:26.026288Z","caller":"traceutil/trace.go:171","msg":"trace[664057080] transaction","detail":"{read_only:false; response_revision:22047; number_of_response:1; }","duration":"127.352606ms","start":"2026-04-14T07:03:25.898888Z","end":"2026-04-14T07:03:26.02624Z","steps":["trace[664057080] 'process raft request' (duration: 127.049979ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:28.180973Z","caller":"traceutil/trace.go:171","msg":"trace[1752616118] transaction","detail":"{read_only:false; response_revision:22060; number_of_response:1; }","duration":"225.974286ms","start":"2026-04-14T07:03:27.954971Z","end":"2026-04-14T07:03:28.180945Z","steps":["trace[1752616118] 'process raft request' (duration: 225.760462ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:28.353809Z","caller":"traceutil/trace.go:171","msg":"trace[1461307831] transaction","detail":"{read_only:false; response_revision:22061; number_of_response:1; }","duration":"365.164666ms","start":"2026-04-14T07:03:27.98859Z","end":"2026-04-14T07:03:28.353755Z","steps":["trace[1461307831] 'process raft request' (duration: 364.541873ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:28.353819Z","caller":"traceutil/trace.go:171","msg":"trace[77122606] transaction","detail":"{read_only:false; response_revision:22062; number_of_response:1; }","duration":"321.442031ms","start":"2026-04-14T07:03:28.03232Z","end":"2026-04-14T07:03:28.353762Z","steps":["trace[77122606] 'process raft request' (duration: 321.284837ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:28.354033Z","caller":"traceutil/trace.go:171","msg":"trace[1135173325] linearizableReadLoop","detail":"{readStateIndex:22714; appliedIndex:22712; }","duration":"361.417596ms","start":"2026-04-14T07:03:27.992575Z","end":"2026-04-14T07:03:28.353993Z","steps":["trace[1135173325] 'read index received' (duration: 188.177538ms)","trace[1135173325] 'applied index is now lower than readState.Index' (duration: 173.236758ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:28.35408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:27.988565Z","time spent":"365.3679ms","remote":"127.0.0.1:54620","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-04-14T07:03:28.354229Z","caller":"traceutil/trace.go:171","msg":"trace[987640322] transaction","detail":"{read_only:false; response_revision:22063; number_of_response:1; }","duration":"321.049531ms","start":"2026-04-14T07:03:28.033127Z","end":"2026-04-14T07:03:28.354176Z","steps":["trace[987640322] 'process raft request' (duration: 320.558251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:28.354394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:28.033098Z","time spent":"321.188055ms","remote":"127.0.0.1:54620","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-04-14T07:03:28.354471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.867786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-04-14T07:03:28.354518Z","caller":"traceutil/trace.go:171","msg":"trace[282355969] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22063; }","duration":"361.967258ms","start":"2026-04-14T07:03:27.992538Z","end":"2026-04-14T07:03:28.354505Z","steps":["trace[282355969] 'agreement among raft nodes before linearized reading' (duration: 361.620551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:28.354507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:28.032282Z","time spent":"321.656675ms","remote":"127.0.0.1:54620","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-04-14T07:03:28.354559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:27.992525Z","time spent":"362.022149ms","remote":"127.0.0.1:54544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-14T07:03:28.360577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.48537ms","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-04-14T07:03:28.360697Z","caller":"traceutil/trace.go:171","msg":"trace[1006666455] 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:22065; }","duration":"342.633223ms","start":"2026-04-14T07:03:28.018012Z","end":"2026-04-14T07:03:28.360646Z","steps":["trace[1006666455] 'agreement among raft nodes before linearized reading' (duration: 341.642712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:28.360746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:28.017989Z","time spent":"342.743097ms","remote":"127.0.0.1:44994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-04-14T07:03:28.374451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.219708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T07:03:28.374609Z","caller":"traceutil/trace.go:171","msg":"trace[1717990850] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:22065; }","duration":"259.354731ms","start":"2026-04-14T07:03:28.115176Z","end":"2026-04-14T07:03:28.374531Z","steps":["trace[1717990850] 'agreement among raft nodes before linearized reading' (duration: 244.595175ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:33.214259Z","caller":"traceutil/trace.go:171","msg":"trace[1174511817] transaction","detail":"{read_only:false; response_revision:22097; number_of_response:1; }","duration":"147.850715ms","start":"2026-04-14T07:03:33.066375Z","end":"2026-04-14T07:03:33.214226Z","steps":["trace[1174511817] 'process raft request' (duration: 147.638521ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:33.95416Z","caller":"traceutil/trace.go:171","msg":"trace[1741205487] transaction","detail":"{read_only:false; response_revision:22103; number_of_response:1; }","duration":"112.011647ms","start":"2026-04-14T07:03:33.842103Z","end":"2026-04-14T07:03:33.954114Z","steps":["trace[1741205487] 'process raft request' (duration: 111.920855ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:33.95424Z","caller":"traceutil/trace.go:171","msg":"trace[1396850814] transaction","detail":"{read_only:false; response_revision:22102; number_of_response:1; }","duration":"130.37828ms","start":"2026-04-14T07:03:33.82377Z","end":"2026-04-14T07:03:33.954148Z","steps":["trace[1396850814] 'process raft request' (duration: 129.865059ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:40.180605Z","caller":"traceutil/trace.go:171","msg":"trace[443752004] linearizableReadLoop","detail":"{readStateIndex:22803; appliedIndex:22802; }","duration":"134.183333ms","start":"2026-04-14T07:03:40.046327Z","end":"2026-04-14T07:03:40.180511Z","steps":["trace[443752004] 'read index received' (duration: 38.399943ms)","trace[443752004] 'applied index is now lower than readState.Index' (duration: 95.763349ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:40.180655Z","caller":"traceutil/trace.go:171","msg":"trace[185839176] transaction","detail":"{read_only:false; response_revision:22147; number_of_response:1; }","duration":"165.684447ms","start":"2026-04-14T07:03:40.01492Z","end":"2026-04-14T07:03:40.180604Z","steps":["trace[185839176] 'process raft request' (duration: 69.949038ms)","trace[185839176] 'compare' (duration: 95.396411ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:40.181132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.759955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:03:40.181297Z","caller":"traceutil/trace.go:171","msg":"trace[1196686603] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:22147; }","duration":"134.961569ms","start":"2026-04-14T07:03:40.046274Z","end":"2026-04-14T07:03:40.181235Z","steps":["trace[1196686603] 'agreement among raft nodes before linearized reading' (duration: 134.503819ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:40.736003Z","caller":"traceutil/trace.go:171","msg":"trace[1977647511] transaction","detail":"{read_only:false; response_revision:22149; number_of_response:1; }","duration":"324.044286ms","start":"2026-04-14T07:03:40.411932Z","end":"2026-04-14T07:03:40.735976Z","steps":["trace[1977647511] 'process raft request' (duration: 237.089955ms)","trace[1977647511] 'compare' (duration: 86.39878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:40.736155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:40.411905Z","time spent":"324.17381ms","remote":"127.0.0.1:54620","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-04-14T07:03:40.736295Z","caller":"traceutil/trace.go:171","msg":"trace[748899457] transaction","detail":"{read_only:false; response_revision:22150; number_of_response:1; }","duration":"323.933183ms","start":"2026-04-14T07:03:40.412308Z","end":"2026-04-14T07:03:40.736241Z","steps":["trace[748899457] 'process raft request' (duration: 323.590066ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:40.736472Z","caller":"traceutil/trace.go:171","msg":"trace[1912046123] transaction","detail":"{read_only:false; response_revision:22151; number_of_response:1; }","duration":"324.049366ms","start":"2026-04-14T07:03:40.412412Z","end":"2026-04-14T07:03:40.736462Z","steps":["trace[1912046123] 'process raft request' (duration: 323.722719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:40.736513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:40.412016Z","time spent":"324.474475ms","remote":"127.0.0.1:54620","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-04-14T07:03:40.736532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:40.412279Z","time spent":"324.131297ms","remote":"127.0.0.1:54620","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-04-14T07:03:40.736477Z","caller":"traceutil/trace.go:171","msg":"trace[334098250] linearizableReadLoop","detail":"{readStateIndex:22808; appliedIndex:22803; }","duration":"322.3736ms","start":"2026-04-14T07:03:40.414075Z","end":"2026-04-14T07:03:40.736449Z","steps":["trace[334098250] 'read index received' (duration: 47.084108ms)","trace[334098250] 'applied index is now lower than readState.Index' (duration: 275.288772ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:40.736575Z","caller":"traceutil/trace.go:171","msg":"trace[866226606] transaction","detail":"{read_only:false; response_revision:22152; number_of_response:1; }","duration":"323.512795ms","start":"2026-04-14T07:03:40.412996Z","end":"2026-04-14T07:03:40.736509Z","steps":["trace[866226606] 'process raft request' (duration: 323.380542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:40.736682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.436653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:03:40.736708Z","caller":"traceutil/trace.go:171","msg":"trace[705331002] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:22153; }","duration":"245.505855ms","start":"2026-04-14T07:03:40.491194Z","end":"2026-04-14T07:03:40.7367Z","steps":["trace[705331002] 'agreement among raft nodes before linearized reading' (duration: 245.455374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:40.736709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.606945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-5499cfb798-k5c22.18a627272d1ec1c5\" ","response":"range_response_count:1 size:890"} {"level":"info","ts":"2026-04-14T07:03:40.736754Z","caller":"traceutil/trace.go:171","msg":"trace[142770547] range","detail":"{range_begin:/registry/events/openstack/magnum-api-5499cfb798-k5c22.18a627272d1ec1c5; range_end:; response_count:1; response_revision:22153; }","duration":"322.708447ms","start":"2026-04-14T07:03:40.414033Z","end":"2026-04-14T07:03:40.736742Z","steps":["trace[142770547] 'agreement among raft nodes before linearized reading' (duration: 322.500823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:40.736789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:40.414021Z","time spent":"322.760938ms","remote":"127.0.0.1:54424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":913,"request content":"key:\"/registry/events/openstack/magnum-api-5499cfb798-k5c22.18a627272d1ec1c5\" "} {"level":"warn","ts":"2026-04-14T07:03:40.736792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:40.412981Z","time spent":"323.701698ms","remote":"127.0.0.1:54620","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-04-14T07:03:40.736901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.249361ms","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-04-14T07:03:40.736975Z","caller":"traceutil/trace.go:171","msg":"trace[1984090795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22153; }","duration":"175.337193ms","start":"2026-04-14T07:03:40.561622Z","end":"2026-04-14T07:03:40.736959Z","steps":["trace[1984090795] 'agreement among raft nodes before linearized reading' (duration: 175.107158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:40.737013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.789597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:03:40.737047Z","caller":"traceutil/trace.go:171","msg":"trace[1053613114] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:22153; }","duration":"185.883199ms","start":"2026-04-14T07:03:40.551153Z","end":"2026-04-14T07:03:40.737036Z","steps":["trace[1053613114] 'agreement among raft nodes before linearized reading' (duration: 185.805057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:40.737236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.244277ms","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-04-14T07:03:40.736564Z","caller":"traceutil/trace.go:171","msg":"trace[99121465] transaction","detail":"{read_only:false; response_revision:22153; number_of_response:1; }","duration":"299.758376ms","start":"2026-04-14T07:03:40.436761Z","end":"2026-04-14T07:03:40.736519Z","steps":["trace[99121465] 'process raft request' (duration: 299.648503ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:40.737271Z","caller":"traceutil/trace.go:171","msg":"trace[69576441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22153; }","duration":"179.303518ms","start":"2026-04-14T07:03:40.557944Z","end":"2026-04-14T07:03:40.737248Z","steps":["trace[69576441] 'agreement among raft nodes before linearized reading' (duration: 178.851698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:40.737383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T07:03:40.436729Z","time spent":"300.567514ms","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T07:03:40.916072Z","caller":"traceutil/trace.go:171","msg":"trace[628809698] transaction","detail":"{read_only:false; response_revision:22154; number_of_response:1; }","duration":"176.860696ms","start":"2026-04-14T07:03:40.739185Z","end":"2026-04-14T07:03:40.916046Z","steps":["trace[628809698] 'process raft request' (duration: 128.671834ms)","trace[628809698] 'compare' (duration: 47.952227ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:40.916302Z","caller":"traceutil/trace.go:171","msg":"trace[443938575] transaction","detail":"{read_only:false; response_revision:22155; number_of_response:1; }","duration":"175.608209ms","start":"2026-04-14T07:03:40.740668Z","end":"2026-04-14T07:03:40.916277Z","steps":["trace[443938575] 'process raft request' (duration: 175.310502ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:40.916441Z","caller":"traceutil/trace.go:171","msg":"trace[820260061] transaction","detail":"{read_only:false; response_revision:22156; number_of_response:1; }","duration":"175.459525ms","start":"2026-04-14T07:03:40.74095Z","end":"2026-04-14T07:03:40.91641Z","steps":["trace[820260061] 'process raft request' (duration: 175.251811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:41.299772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.90101ms","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-04-14T07:03:41.299862Z","caller":"traceutil/trace.go:171","msg":"trace[794307126] 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:22156; }","duration":"173.118845ms","start":"2026-04-14T07:03:41.126722Z","end":"2026-04-14T07:03:41.299841Z","steps":["trace[794307126] 'range keys from in-memory index tree' (duration: 172.797299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:47.754049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.937823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:03:47.754261Z","caller":"traceutil/trace.go:171","msg":"trace[1031367789] transaction","detail":"{read_only:false; response_revision:22229; number_of_response:1; }","duration":"214.299307ms","start":"2026-04-14T07:03:47.539927Z","end":"2026-04-14T07:03:47.754226Z","steps":["trace[1031367789] 'process raft request' (duration: 33.009417ms)","trace[1031367789] 'compare' (duration: 180.698138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:48.089242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.52826ms","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-04-14T07:03:48.089368Z","caller":"traceutil/trace.go:171","msg":"trace[869495242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22229; }","duration":"149.702834ms","start":"2026-04-14T07:03:47.939633Z","end":"2026-04-14T07:03:48.089336Z","steps":["trace[869495242] 'range keys from in-memory index tree' (duration: 149.261235ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:48.365021Z","caller":"traceutil/trace.go:171","msg":"trace[1647663105] linearizableReadLoop","detail":"{readStateIndex:22888; appliedIndex:22887; }","duration":"145.444593ms","start":"2026-04-14T07:03:48.219552Z","end":"2026-04-14T07:03:48.364997Z","steps":["trace[1647663105] 'read index received' (duration: 145.365871ms)","trace[1647663105] 'applied index is now lower than readState.Index' (duration: 77.412µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:03:48.365271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.705299ms","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-04-14T07:03:48.365312Z","caller":"traceutil/trace.go:171","msg":"trace[689987839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22231; }","duration":"145.820292ms","start":"2026-04-14T07:03:48.219481Z","end":"2026-04-14T07:03:48.365301Z","steps":["trace[689987839] 'agreement among raft nodes before linearized reading' (duration: 145.624957ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:48.365436Z","caller":"traceutil/trace.go:171","msg":"trace[1035823799] transaction","detail":"{read_only:false; response_revision:22231; number_of_response:1; }","duration":"270.868839ms","start":"2026-04-14T07:03:48.094558Z","end":"2026-04-14T07:03:48.365427Z","steps":["trace[1035823799] 'process raft request' (duration: 270.273405ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:48.39636Z","caller":"traceutil/trace.go:171","msg":"trace[1133449195] transaction","detail":"{read_only:false; response_revision:22232; number_of_response:1; }","duration":"170.719494ms","start":"2026-04-14T07:03:48.225563Z","end":"2026-04-14T07:03:48.396283Z","steps":["trace[1133449195] 'process raft request' (duration: 170.268274ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:48.396592Z","caller":"traceutil/trace.go:171","msg":"trace[235920314] transaction","detail":"{read_only:false; response_revision:22233; number_of_response:1; }","duration":"154.449326ms","start":"2026-04-14T07:03:48.242103Z","end":"2026-04-14T07:03:48.396552Z","steps":["trace[235920314] 'process raft request' (duration: 153.968115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:03:48.618513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.553267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:03:48.618783Z","caller":"traceutil/trace.go:171","msg":"trace[2102030908] transaction","detail":"{read_only:false; response_revision:22235; number_of_response:1; }","duration":"248.198633ms","start":"2026-04-14T07:03:48.370535Z","end":"2026-04-14T07:03:48.618733Z","steps":["trace[2102030908] 'process raft request' (duration: 143.316828ms)","trace[2102030908] 'compare' (duration: 104.372364ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:03:48.618884Z","caller":"traceutil/trace.go:171","msg":"trace[309415160] transaction","detail":"{read_only:false; response_revision:22236; number_of_response:1; }","duration":"115.632815ms","start":"2026-04-14T07:03:48.50322Z","end":"2026-04-14T07:03:48.618852Z","steps":["trace[309415160] 'process raft request' (duration: 115.426831ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:51.487909Z","caller":"traceutil/trace.go:171","msg":"trace[1123275030] transaction","detail":"{read_only:false; response_revision:22262; number_of_response:1; }","duration":"116.014112ms","start":"2026-04-14T07:03:51.371868Z","end":"2026-04-14T07:03:51.487883Z","steps":["trace[1123275030] 'process raft request' (duration: 115.851959ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:03:53.261299Z","caller":"traceutil/trace.go:171","msg":"trace[729925768] transaction","detail":"{read_only:false; response_revision:22280; number_of_response:1; }","duration":"118.566418ms","start":"2026-04-14T07:03:53.142701Z","end":"2026-04-14T07:03:53.261268Z","steps":["trace[729925768] 'process raft request' (duration: 118.364294ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:04:12.947895Z","caller":"traceutil/trace.go:171","msg":"trace[1619694932] linearizableReadLoop","detail":"{readStateIndex:23101; appliedIndex:23100; }","duration":"216.257529ms","start":"2026-04-14T07:04:12.731612Z","end":"2026-04-14T07:04:12.947869Z","steps":["trace[1619694932] 'read index received' (duration: 216.140277ms)","trace[1619694932] 'applied index is now lower than readState.Index' (duration: 115.482µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T07:04:12.948166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.530926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T07:04:12.948216Z","caller":"traceutil/trace.go:171","msg":"trace[956859658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22439; }","duration":"216.657038ms","start":"2026-04-14T07:04:12.731547Z","end":"2026-04-14T07:04:12.948204Z","steps":["trace[956859658] 'agreement among raft nodes before linearized reading' (duration: 216.439614ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:04:12.948147Z","caller":"traceutil/trace.go:171","msg":"trace[1211805947] transaction","detail":"{read_only:false; response_revision:22439; number_of_response:1; }","duration":"230.979275ms","start":"2026-04-14T07:04:12.717127Z","end":"2026-04-14T07:04:12.948106Z","steps":["trace[1211805947] 'process raft request' (duration: 230.516715ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:04:13.150215Z","caller":"traceutil/trace.go:171","msg":"trace[477501711] transaction","detail":"{read_only:false; response_revision:22444; number_of_response:1; }","duration":"195.312921ms","start":"2026-04-14T07:04:12.954839Z","end":"2026-04-14T07:04:13.150152Z","steps":["trace[477501711] 'process raft request' (duration: 195.038685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:04:13.421433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.390564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T07:04:13.421617Z","caller":"traceutil/trace.go:171","msg":"trace[1455314317] transaction","detail":"{read_only:false; response_revision:22445; number_of_response:1; }","duration":"265.853841ms","start":"2026-04-14T07:04:13.155746Z","end":"2026-04-14T07:04:13.421599Z","steps":["trace[1455314317] 'process raft request' (duration: 126.191911ms)","trace[1455314317] 'compare' (duration: 138.533775ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:04:13.421878Z","caller":"traceutil/trace.go:171","msg":"trace[1871699795] transaction","detail":"{read_only:false; response_revision:22446; number_of_response:1; }","duration":"266.050346ms","start":"2026-04-14T07:04:13.155789Z","end":"2026-04-14T07:04:13.421839Z","steps":["trace[1871699795] 'process raft request' (duration: 265.76103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:04:13.739612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.172954ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-14T07:04:13.740016Z","caller":"traceutil/trace.go:171","msg":"trace[2092006431] linearizableReadLoop","detail":"{readStateIndex:23111; appliedIndex:23108; }","duration":"103.903944ms","start":"2026-04-14T07:04:13.636084Z","end":"2026-04-14T07:04:13.739988Z","steps":["trace[2092006431] 'read index received' (duration: 99.49853ms)","trace[2092006431] 'applied index is now lower than readState.Index' (duration: 4.403974ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T07:04:13.740497Z","caller":"traceutil/trace.go:171","msg":"trace[420872363] transaction","detail":"{read_only:false; response_revision:22448; number_of_response:1; }","duration":"147.60656ms","start":"2026-04-14T07:04:13.592843Z","end":"2026-04-14T07:04:13.74045Z","steps":["trace[420872363] 'process raft request' (duration: 147.091208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T07:04:13.740652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.536438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T07:04:13.740773Z","caller":"traceutil/trace.go:171","msg":"trace[176554871] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:22448; }","duration":"104.709412ms","start":"2026-04-14T07:04:13.636032Z","end":"2026-04-14T07:04:13.740742Z","steps":["trace[176554871] 'agreement among raft nodes before linearized reading' (duration: 104.208982ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:04:13.740502Z","caller":"traceutil/trace.go:171","msg":"trace[246027448] transaction","detail":"{read_only:false; response_revision:22447; number_of_response:1; }","duration":"165.438801ms","start":"2026-04-14T07:04:13.574983Z","end":"2026-04-14T07:04:13.740422Z","steps":["trace[246027448] 'process raft request' (duration: 164.775596ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T07:07:50.675895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21780} {"level":"info","ts":"2026-04-14T07:07:50.833295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21780,"took":"152.512015ms","hash":64822487,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":34361344,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T07:07:50.833385Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":64822487,"revision":21780,"compact-revision":18421} {"level":"info","ts":"2026-04-14T07:08:30.845282Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000624d.wal"} {"level":"info","ts":"2026-04-14T07:12:50.683711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24151} {"level":"info","ts":"2026-04-14T07:12:50.824715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24151,"took":"135.514699ms","hash":2798020272,"current-db-size-bytes":63594496,"current-db-size":"64 MB","current-db-size-in-use-bytes":32514048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T07:12:50.824782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2798020272,"revision":24151,"compact-revision":21780}