{"level":"warn","ts":"2026-05-09T05:13:20.725849Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:13:20.725956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T05:13:20.726041Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T05:13:20.726059Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-09T05:13:20.726099Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:13:20.727001Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-05-09T05:13:20.727095Z","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.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T05:13:20.730396Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.031655ms"} {"level":"info","ts":"2026-05-09T05:13:20.738869Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-05-09T05:13:20.739661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T05:13:20.739685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-05-09T05:13:20.739699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T05:13:20.739711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-05-09T05:13:20.739751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-05-09T05:13:20.746791Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T05:13:20.74813Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T05:13:20.749483Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T05:13:20.751879Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T05:13:20.752007Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:13:20.752098Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:13:20.752109Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T05:13:20.752161Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T05:13:20.753799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-05-09T05:13:20.753924Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-09T05:13:20.755842Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T05:13:20.755922Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-09T05:13:20.755982Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-09T05:13:20.756197Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T05:13:20.756237Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T05:13:21.740149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T05:13:21.740217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T05:13:21.740267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-05-09T05:13:21.740287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-05-09T05:13:21.740298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-09T05:13:21.740321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-05-09T05:13:21.740335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-09T05:13:21.741688Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:13:21.74286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:13:21.742855Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T05:13:21.742938Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T05:13:21.743248Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T05:13:21.743292Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T05:13:21.743478Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:13:21.743603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:13:21.743645Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T05:13:21.746052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T05:13:21.746835Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"warn","ts":"2026-05-09T05:13:41.424723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.91695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-09T05:13:41.424831Z","caller":"traceutil/trace.go:171","msg":"trace[688368874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"184.086164ms","start":"2026-05-09T05:13:41.240728Z","end":"2026-05-09T05:13:41.424814Z","steps":["trace[688368874] 'range keys from in-memory index tree' (duration: 183.777248ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:41.563559Z","caller":"traceutil/trace.go:171","msg":"trace[1888954626] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"112.479589ms","start":"2026-05-09T05:13:41.451027Z","end":"2026-05-09T05:13:41.563506Z","steps":["trace[1888954626] 'read index received' (duration: 112.236334ms)","trace[1888954626] 'applied index is now lower than readState.Index' (duration: 242.105µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:41.563581Z","caller":"traceutil/trace.go:171","msg":"trace[1044057087] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"134.585016ms","start":"2026-05-09T05:13:41.428969Z","end":"2026-05-09T05:13:41.563554Z","steps":["trace[1044057087] 'process raft request' (duration: 134.394272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:41.563707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.662722ms","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-05-09T05:13:41.563743Z","caller":"traceutil/trace.go:171","msg":"trace[721318096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"112.761695ms","start":"2026-05-09T05:13:41.450972Z","end":"2026-05-09T05:13:41.563734Z","steps":["trace[721318096] 'agreement among raft nodes before linearized reading' (duration: 112.670853ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:51.767977Z","caller":"traceutil/trace.go:171","msg":"trace[1402182513] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"100.312162ms","start":"2026-05-09T05:13:51.667633Z","end":"2026-05-09T05:13:51.767945Z","steps":["trace[1402182513] 'process raft request' (duration: 100.105728ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:57.922981Z","caller":"traceutil/trace.go:171","msg":"trace[406770952] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"108.212569ms","start":"2026-05-09T05:13:57.81474Z","end":"2026-05-09T05:13:57.922952Z","steps":["trace[406770952] 'process raft request' (duration: 107.911093ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:58.094355Z","caller":"traceutil/trace.go:171","msg":"trace[631860402] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"276.664811ms","start":"2026-05-09T05:13:57.817664Z","end":"2026-05-09T05:13:58.094329Z","steps":["trace[631860402] 'process raft request' (duration: 271.42217ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:13:58.09921Z","caller":"traceutil/trace.go:171","msg":"trace[1618777757] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:600; }","duration":"239.27358ms","start":"2026-05-09T05:13:57.85988Z","end":"2026-05-09T05:13:58.099154Z","steps":["trace[1618777757] 'read index received' (duration: 62.886739ms)","trace[1618777757] 'applied index is now lower than readState.Index' (duration: 176.385981ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:13:58.099301Z","caller":"traceutil/trace.go:171","msg":"trace[2067023055] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"246.188337ms","start":"2026-05-09T05:13:57.853078Z","end":"2026-05-09T05:13:58.099267Z","steps":["trace[2067023055] 'process raft request' (duration: 241.694302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:58.099355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.450064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9w2wj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-09T05:13:58.0994Z","caller":"traceutil/trace.go:171","msg":"trace[953783993] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9w2wj; range_end:; response_count:1; response_revision:580; }","duration":"239.531606ms","start":"2026-05-09T05:13:57.859855Z","end":"2026-05-09T05:13:58.099386Z","steps":["trace[953783993] 'agreement among raft nodes before linearized reading' (duration: 239.429444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:58.712736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.07547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:13:58.712943Z","caller":"traceutil/trace.go:171","msg":"trace[1484385746] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"558.938001ms","start":"2026-05-09T05:13:58.153988Z","end":"2026-05-09T05:13:58.712926Z","steps":["trace[1484385746] 'process raft request' (duration: 558.863579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:13:58.713015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:58.153961Z","time spent":"559.024482ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T05:13:58.712994Z","caller":"traceutil/trace.go:171","msg":"trace[1349009640] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"595.595017ms","start":"2026-05-09T05:13:58.117345Z","end":"2026-05-09T05:13:58.71294Z","steps":["trace[1349009640] 'process raft request' (duration: 204.550777ms)","trace[1349009640] 'compare' (duration: 389.469527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:13:58.713415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:13:58.117319Z","time spent":"595.857072ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:00.099622Z","caller":"traceutil/trace.go:171","msg":"trace[202780885] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"100.006105ms","start":"2026-05-09T05:13:59.999583Z","end":"2026-05-09T05:14:00.099589Z","steps":["trace[202780885] 'process raft request' (duration: 99.840892ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:00.099627Z","caller":"traceutil/trace.go:171","msg":"trace[1001732387] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"100.172399ms","start":"2026-05-09T05:13:59.999329Z","end":"2026-05-09T05:14:00.099501Z","steps":["trace[1001732387] 'process raft request' (duration: 99.975245ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:14:39.521003Z","caller":"traceutil/trace.go:171","msg":"trace[991272418] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"125.548452ms","start":"2026-05-09T05:14:39.395418Z","end":"2026-05-09T05:14:39.520967Z","steps":["trace[991272418] 'read index received' (duration: 125.352808ms)","trace[991272418] 'applied index is now lower than readState.Index' (duration: 193.954µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:14:39.521068Z","caller":"traceutil/trace.go:171","msg":"trace[1788385076] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"335.898557ms","start":"2026-05-09T05:14:39.185128Z","end":"2026-05-09T05:14:39.521026Z","steps":["trace[1788385076] 'process raft request' (duration: 335.609991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:39.521321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.880718ms","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-05-09T05:14:39.521393Z","caller":"traceutil/trace.go:171","msg":"trace[1788830607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1132; }","duration":"126.039822ms","start":"2026-05-09T05:14:39.395337Z","end":"2026-05-09T05:14:39.521377Z","steps":["trace[1788830607] 'agreement among raft nodes before linearized reading' (duration: 125.786616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:14:39.521525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:14:39.185099Z","time spent":"336.247584ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:14:39.702774Z","caller":"traceutil/trace.go:171","msg":"trace[907335628] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"175.847341ms","start":"2026-05-09T05:14:39.526875Z","end":"2026-05-09T05:14:39.702722Z","steps":["trace[907335628] 'process raft request' (duration: 160.304191ms)","trace[907335628] 'compare' (duration: 15.378667ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:15:02.357824Z","caller":"traceutil/trace.go:171","msg":"trace[1656015509] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"102.397088ms","start":"2026-05-09T05:15:02.255398Z","end":"2026-05-09T05:15:02.357796Z","steps":["trace[1656015509] 'process raft request' (duration: 102.227104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:16:09.309025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.171472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:16:09.30915Z","caller":"traceutil/trace.go:171","msg":"trace[329265162] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2086; }","duration":"149.365897ms","start":"2026-05-09T05:16:09.159758Z","end":"2026-05-09T05:16:09.309124Z","steps":["trace[329265162] 'count revisions from in-memory index tree' (duration: 149.075509ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:17:38.646733Z","caller":"traceutil/trace.go:171","msg":"trace[472542470] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"210.808347ms","start":"2026-05-09T05:17:38.435892Z","end":"2026-05-09T05:17:38.6467Z","steps":["trace[472542470] 'process raft request' (duration: 188.891101ms)","trace[472542470] 'compare' (duration: 21.771773ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:17:38.647334Z","caller":"traceutil/trace.go:171","msg":"trace[436511706] linearizableReadLoop","detail":"{readStateIndex:2868; appliedIndex:2867; }","duration":"153.257013ms","start":"2026-05-09T05:17:38.49406Z","end":"2026-05-09T05:17:38.647317Z","steps":["trace[436511706] 'read index received' (duration: 130.727084ms)","trace[436511706] 'applied index is now lower than readState.Index' (duration: 22.528399ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:17:38.647632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.485748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:17:38.64773Z","caller":"traceutil/trace.go:171","msg":"trace[392616058] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2760; }","duration":"153.700402ms","start":"2026-05-09T05:17:38.494007Z","end":"2026-05-09T05:17:38.647708Z","steps":["trace[392616058] 'agreement among raft nodes before linearized reading' (duration: 153.447137ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:17:39.067357Z","caller":"traceutil/trace.go:171","msg":"trace[1193408713] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"127.003194ms","start":"2026-05-09T05:17:38.940333Z","end":"2026-05-09T05:17:39.067336Z","steps":["trace[1193408713] 'process raft request' (duration: 126.83629ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:17:44.582217Z","caller":"traceutil/trace.go:171","msg":"trace[1608284816] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"119.31783ms","start":"2026-05-09T05:17:44.46287Z","end":"2026-05-09T05:17:44.582188Z","steps":["trace[1608284816] 'process raft request' (duration: 119.147126ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:20:09.85294Z","caller":"traceutil/trace.go:171","msg":"trace[2078281574] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"106.415891ms","start":"2026-05-09T05:20:09.746489Z","end":"2026-05-09T05:20:09.852905Z","steps":["trace[2078281574] 'process raft request' (duration: 106.215007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:21:27.331785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.995668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-09T05:21:27.331888Z","caller":"traceutil/trace.go:171","msg":"trace[967205645] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4650; }","duration":"157.129001ms","start":"2026-05-09T05:21:27.174735Z","end":"2026-05-09T05:21:27.331864Z","steps":["trace[967205645] 'range keys from in-memory index tree' (duration: 154.099497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:21:27.331906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.273387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:21:27.332098Z","caller":"traceutil/trace.go:171","msg":"trace[1180462356] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"153.584506ms","start":"2026-05-09T05:21:27.178499Z","end":"2026-05-09T05:21:27.332083Z","steps":["trace[1180462356] 'compare' (duration: 150.059363ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:21:27.332639Z","caller":"traceutil/trace.go:171","msg":"trace[911718517] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4651; }","duration":"140.997652ms","start":"2026-05-09T05:21:27.191631Z","end":"2026-05-09T05:21:27.332629Z","steps":["trace[911718517] 'process raft request' (duration: 140.879499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:21:37.49605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.431501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:21:37.496193Z","caller":"traceutil/trace.go:171","msg":"trace[1982653205] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4970; }","duration":"101.636115ms","start":"2026-05-09T05:21:37.394535Z","end":"2026-05-09T05:21:37.496171Z","steps":["trace[1982653205] 'count revisions from in-memory index tree' (duration: 101.289288ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:21:46.623213Z","caller":"traceutil/trace.go:171","msg":"trace[1120864862] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"192.985226ms","start":"2026-05-09T05:21:46.430193Z","end":"2026-05-09T05:21:46.623178Z","steps":["trace[1120864862] 'process raft request' (duration: 164.965165ms)","trace[1120864862] 'compare' (duration: 27.791276ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:21:46.623228Z","caller":"traceutil/trace.go:171","msg":"trace[1831322101] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"192.882043ms","start":"2026-05-09T05:21:46.430318Z","end":"2026-05-09T05:21:46.6232Z","steps":["trace[1831322101] 'process raft request' (duration: 192.781641ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:21:46.62357Z","caller":"traceutil/trace.go:171","msg":"trace[1696559924] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"192.957295ms","start":"2026-05-09T05:21:46.430599Z","end":"2026-05-09T05:21:46.623557Z","steps":["trace[1696559924] 'process raft request' (duration: 192.543466ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:21:46.623582Z","caller":"traceutil/trace.go:171","msg":"trace[1404637802] linearizableReadLoop","detail":"{readStateIndex:5357; appliedIndex:5353; }","duration":"172.739599ms","start":"2026-05-09T05:21:46.45082Z","end":"2026-05-09T05:21:46.62356Z","steps":["trace[1404637802] 'read index received' (duration: 144.30949ms)","trace[1404637802] 'applied index is now lower than readState.Index' (duration: 28.429119ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:21:46.623636Z","caller":"traceutil/trace.go:171","msg":"trace[1096342964] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"192.625437ms","start":"2026-05-09T05:21:46.430996Z","end":"2026-05-09T05:21:46.623621Z","steps":["trace[1096342964] 'process raft request' (duration: 192.422563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:21:46.623841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.865921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-09T05:21:46.623835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.992234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-09T05:21:46.623876Z","caller":"traceutil/trace.go:171","msg":"trace[796392482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5160; }","duration":"172.966573ms","start":"2026-05-09T05:21:46.450898Z","end":"2026-05-09T05:21:46.623865Z","steps":["trace[796392482] 'agreement among raft nodes before linearized reading' (duration: 172.80933ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:21:46.623913Z","caller":"traceutil/trace.go:171","msg":"trace[1697722624] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5160; }","duration":"173.076667ms","start":"2026-05-09T05:21:46.450814Z","end":"2026-05-09T05:21:46.623891Z","steps":["trace[1697722624] 'agreement among raft nodes before linearized reading' (duration: 172.877992ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:21:54.134322Z","caller":"traceutil/trace.go:171","msg":"trace[1900074043] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"208.358179ms","start":"2026-05-09T05:21:53.925941Z","end":"2026-05-09T05:21:54.134299Z","steps":["trace[1900074043] 'process raft request' (duration: 208.234626ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:21:54.18001Z","caller":"traceutil/trace.go:171","msg":"trace[955988490] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"250.054188ms","start":"2026-05-09T05:21:53.929933Z","end":"2026-05-09T05:21:54.179988Z","steps":["trace[955988490] 'process raft request' (duration: 249.875154ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:22:45.081439Z","caller":"traceutil/trace.go:171","msg":"trace[275755742] linearizableReadLoop","detail":"{readStateIndex:6039; appliedIndex:6038; }","duration":"131.193002ms","start":"2026-05-09T05:22:44.950221Z","end":"2026-05-09T05:22:45.081414Z","steps":["trace[275755742] 'read index received' (duration: 131.002018ms)","trace[275755742] 'applied index is now lower than readState.Index' (duration: 190.124µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:22:45.081544Z","caller":"traceutil/trace.go:171","msg":"trace[2104345732] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"150.361647ms","start":"2026-05-09T05:22:44.931159Z","end":"2026-05-09T05:22:45.081521Z","steps":["trace[2104345732] 'process raft request' (duration: 150.094221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:22:45.082197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.930438ms","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-05-09T05:22:45.082563Z","caller":"traceutil/trace.go:171","msg":"trace[541619089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5827; }","duration":"132.180183ms","start":"2026-05-09T05:22:44.950138Z","end":"2026-05-09T05:22:45.082319Z","steps":["trace[541619089] 'agreement among raft nodes before linearized reading' (duration: 131.411887ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:23:21.766682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-05-09T05:23:21.850774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"82.334884ms","hash":1198806162,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":31760384,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T05:23:21.850852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1198806162,"revision":3001,"compact-revision":-1} {"level":"warn","ts":"2026-05-09T05:27:50.544776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.718464ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-09T05:27:50.544889Z","caller":"traceutil/trace.go:171","msg":"trace[1913654398] linearizableReadLoop","detail":"{readStateIndex:8396; appliedIndex:8395; }","duration":"157.217333ms","start":"2026-05-09T05:27:50.387655Z","end":"2026-05-09T05:27:50.544872Z","steps":["trace[1913654398] 'read index received' (duration: 43.181µs)","trace[1913654398] 'applied index is now lower than readState.Index' (duration: 157.172752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:27:50.545102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.490011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-09T05:27:50.545116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.442387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T05:27:50.545158Z","caller":"traceutil/trace.go:171","msg":"trace[686809710] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8111; }","duration":"150.586512ms","start":"2026-05-09T05:27:50.394553Z","end":"2026-05-09T05:27:50.545139Z","steps":["trace[686809710] 'agreement among raft nodes before linearized reading' (duration: 150.415709ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:27:50.545199Z","caller":"traceutil/trace.go:171","msg":"trace[676337103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8111; }","duration":"157.57315ms","start":"2026-05-09T05:27:50.387604Z","end":"2026-05-09T05:27:50.545177Z","steps":["trace[676337103] 'agreement among raft nodes before linearized reading' (duration: 157.332585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:01.985735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.567239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:28:01.985824Z","caller":"traceutil/trace.go:171","msg":"trace[934709745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8170; }","duration":"210.724553ms","start":"2026-05-09T05:28:01.77508Z","end":"2026-05-09T05:28:01.985804Z","steps":["trace[934709745] 'range keys from in-memory index tree' (duration: 210.469318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:01.98582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.541982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-09T05:28:01.985891Z","caller":"traceutil/trace.go:171","msg":"trace[1339949269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8170; }","duration":"203.648214ms","start":"2026-05-09T05:28:01.782225Z","end":"2026-05-09T05:28:01.985874Z","steps":["trace[1339949269] 'range keys from in-memory index tree' (duration: 203.405499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:01.985899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.525604ms","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:5"} {"level":"info","ts":"2026-05-09T05:28:01.986006Z","caller":"traceutil/trace.go:171","msg":"trace[1812283906] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:8170; }","duration":"231.684666ms","start":"2026-05-09T05:28:01.754291Z","end":"2026-05-09T05:28:01.985976Z","steps":["trace[1812283906] 'count revisions from in-memory index tree' (duration: 231.440351ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:02.112876Z","caller":"traceutil/trace.go:171","msg":"trace[1748684087] transaction","detail":"{read_only:false; response_revision:8172; number_of_response:1; }","duration":"121.863275ms","start":"2026-05-09T05:28:01.990983Z","end":"2026-05-09T05:28:02.112847Z","steps":["trace[1748684087] 'process raft request' (duration: 119.125157ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:02.113Z","caller":"traceutil/trace.go:171","msg":"trace[1565903257] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"121.66871ms","start":"2026-05-09T05:28:01.991285Z","end":"2026-05-09T05:28:02.112954Z","steps":["trace[1565903257] 'process raft request' (duration: 121.310263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:02.921287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.983443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:28:02.921407Z","caller":"traceutil/trace.go:171","msg":"trace[662180976] linearizableReadLoop","detail":"{readStateIndex:8466; appliedIndex:8465; }","duration":"277.781451ms","start":"2026-05-09T05:28:02.643605Z","end":"2026-05-09T05:28:02.921386Z","steps":["trace[662180976] 'read index received' (duration: 62.622834ms)","trace[662180976] 'applied index is now lower than readState.Index' (duration: 215.157417ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:28:02.921418Z","caller":"traceutil/trace.go:171","msg":"trace[247689509] transaction","detail":"{read_only:false; response_revision:8178; number_of_response:1; }","duration":"284.34325ms","start":"2026-05-09T05:28:02.637054Z","end":"2026-05-09T05:28:02.921397Z","steps":["trace[247689509] 'process raft request' (duration: 69.128412ms)","trace[247689509] 'compare' (duration: 214.658136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:28:02.921944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.328692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-09T05:28:02.921999Z","caller":"traceutil/trace.go:171","msg":"trace[1479916508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8178; }","duration":"278.405033ms","start":"2026-05-09T05:28:02.643579Z","end":"2026-05-09T05:28:02.921984Z","steps":["trace[1479916508] 'agreement among raft nodes before linearized reading' (duration: 277.905753ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:03.421474Z","caller":"traceutil/trace.go:171","msg":"trace[245533562] transaction","detail":"{read_only:false; response_revision:8181; number_of_response:1; }","duration":"107.968471ms","start":"2026-05-09T05:28:03.313485Z","end":"2026-05-09T05:28:03.421454Z","steps":["trace[245533562] 'process raft request' (duration: 107.818628ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:04.108773Z","caller":"traceutil/trace.go:171","msg":"trace[1646816048] linearizableReadLoop","detail":"{readStateIndex:8472; appliedIndex:8471; }","duration":"152.097585ms","start":"2026-05-09T05:28:03.956654Z","end":"2026-05-09T05:28:04.108751Z","steps":["trace[1646816048] 'read index received' (duration: 151.919521ms)","trace[1646816048] 'applied index is now lower than readState.Index' (duration: 177.264µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:28:04.109017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.35134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-09T05:28:04.109077Z","caller":"traceutil/trace.go:171","msg":"trace[863897250] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8184; }","duration":"152.492224ms","start":"2026-05-09T05:28:03.956577Z","end":"2026-05-09T05:28:04.10907Z","steps":["trace[863897250] 'agreement among raft nodes before linearized reading' (duration: 152.252849ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:04.109381Z","caller":"traceutil/trace.go:171","msg":"trace[466590650] transaction","detail":"{read_only:false; response_revision:8184; number_of_response:1; }","duration":"162.825301ms","start":"2026-05-09T05:28:03.945952Z","end":"2026-05-09T05:28:04.108778Z","steps":["trace[466590650] 'process raft request' (duration: 162.641167ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:21.777885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6110} {"level":"info","ts":"2026-05-09T05:28:21.87111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6110,"took":"91.099776ms","hash":3633995109,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":23134208,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-09T05:28:21.871184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3633995109,"revision":6110,"compact-revision":3001} {"level":"warn","ts":"2026-05-09T05:28:43.499777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.049559ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:28:43.50021Z","caller":"traceutil/trace.go:171","msg":"trace[1711190543] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8489; }","duration":"190.475728ms","start":"2026-05-09T05:28:43.309693Z","end":"2026-05-09T05:28:43.500169Z","steps":["trace[1711190543] 'range keys from in-memory index tree' (duration: 190.033109ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:43.499872Z","caller":"traceutil/trace.go:171","msg":"trace[2077120350] linearizableReadLoop","detail":"{readStateIndex:8791; appliedIndex:8790; }","duration":"233.1119ms","start":"2026-05-09T05:28:43.266734Z","end":"2026-05-09T05:28:43.499846Z","steps":["trace[2077120350] 'read index received' (duration: 175.642125ms)","trace[2077120350] 'applied index is now lower than readState.Index' (duration: 57.468555ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:28:43.499895Z","caller":"traceutil/trace.go:171","msg":"trace[965589474] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"235.331387ms","start":"2026-05-09T05:28:43.264528Z","end":"2026-05-09T05:28:43.499859Z","steps":["trace[965589474] 'process raft request' (duration: 177.884233ms)","trace[965589474] 'compare' (duration: 57.22117ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:28:43.500051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.298034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-detect-version\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-09T05:28:43.500718Z","caller":"traceutil/trace.go:171","msg":"trace[492745352] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-detect-version; range_end:; response_count:1; response_revision:8490; }","duration":"233.995318ms","start":"2026-05-09T05:28:43.266697Z","end":"2026-05-09T05:28:43.500692Z","steps":["trace[492745352] 'agreement among raft nodes before linearized reading' (duration: 233.224362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:43.500785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.546628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-05-09T05:28:43.500853Z","caller":"traceutil/trace.go:171","msg":"trace[111032952] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8490; }","duration":"233.620441ms","start":"2026-05-09T05:28:43.267215Z","end":"2026-05-09T05:28:43.500835Z","steps":["trace[111032952] 'agreement among raft nodes before linearized reading' (duration: 233.448217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:43.501034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.889008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-09T05:28:43.501109Z","caller":"traceutil/trace.go:171","msg":"trace[539236467] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:8490; }","duration":"192.986921ms","start":"2026-05-09T05:28:43.308105Z","end":"2026-05-09T05:28:43.501092Z","steps":["trace[539236467] 'agreement among raft nodes before linearized reading' (duration: 192.705645ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:43.997708Z","caller":"traceutil/trace.go:171","msg":"trace[587608343] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"179.517687ms","start":"2026-05-09T05:28:43.818164Z","end":"2026-05-09T05:28:43.997681Z","steps":["trace[587608343] 'process raft request' (duration: 90.501984ms)","trace[587608343] 'compare' (duration: 88.804898ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:28:43.997977Z","caller":"traceutil/trace.go:171","msg":"trace[1758213579] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"176.833349ms","start":"2026-05-09T05:28:43.821116Z","end":"2026-05-09T05:28:43.997949Z","steps":["trace[1758213579] 'process raft request' (duration: 176.525153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:45.15737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.017713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T05:28:45.15771Z","caller":"traceutil/trace.go:171","msg":"trace[1941803565] transaction","detail":"{read_only:false; response_revision:8506; number_of_response:1; }","duration":"242.956289ms","start":"2026-05-09T05:28:44.914691Z","end":"2026-05-09T05:28:45.157647Z","steps":["trace[1941803565] 'process raft request' (duration: 242.805566ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:28:45.157689Z","caller":"traceutil/trace.go:171","msg":"trace[824364837] transaction","detail":"{read_only:false; response_revision:8505; number_of_response:1; }","duration":"265.044446ms","start":"2026-05-09T05:28:44.892601Z","end":"2026-05-09T05:28:45.157645Z","steps":["trace[824364837] 'process raft request' (duration: 62.633035ms)","trace[824364837] 'compare' (duration: 201.836019ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:28:45.157758Z","caller":"traceutil/trace.go:171","msg":"trace[1348142316] linearizableReadLoop","detail":"{readStateIndex:8807; appliedIndex:8806; }","duration":"256.349552ms","start":"2026-05-09T05:28:44.901355Z","end":"2026-05-09T05:28:45.157705Z","steps":["trace[1348142316] 'read index received' (duration: 8.93478ms)","trace[1348142316] 'applied index is now lower than readState.Index' (duration: 247.402492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:28:45.157953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.593467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T05:28:45.158009Z","caller":"traceutil/trace.go:171","msg":"trace[883537738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8506; }","duration":"256.675599ms","start":"2026-05-09T05:28:44.901319Z","end":"2026-05-09T05:28:45.157995Z","steps":["trace[883537738] 'agreement among raft nodes before linearized reading' (duration: 256.480865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:45.158062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.254505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-09T05:28:45.15811Z","caller":"traceutil/trace.go:171","msg":"trace[495737039] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8506; }","duration":"234.339196ms","start":"2026-05-09T05:28:44.923756Z","end":"2026-05-09T05:28:45.158095Z","steps":["trace[495737039] 'agreement among raft nodes before linearized reading' (duration: 234.145472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:28:45.158265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.719371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:28:45.158486Z","caller":"traceutil/trace.go:171","msg":"trace[1292217804] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8506; }","duration":"223.215492ms","start":"2026-05-09T05:28:44.935228Z","end":"2026-05-09T05:28:45.158443Z","steps":["trace[1292217804] 'agreement among raft nodes before linearized reading' (duration: 222.714701ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:29:14.128799Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a3.wal"} {"level":"info","ts":"2026-05-09T05:30:57.962368Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T05:30:57.968495Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T05:30:57.968609Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T05:32:14.886172Z","caller":"traceutil/trace.go:171","msg":"trace[109398366] linearizableReadLoop","detail":"{readStateIndex:10630; appliedIndex:10629; }","duration":"118.186867ms","start":"2026-05-09T05:32:14.767958Z","end":"2026-05-09T05:32:14.886145Z","steps":["trace[109398366] 'read index received' (duration: 68.761815ms)","trace[109398366] 'applied index is now lower than readState.Index' (duration: 49.423522ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:32:14.886365Z","caller":"traceutil/trace.go:171","msg":"trace[1182520812] transaction","detail":"{read_only:false; response_revision:10275; number_of_response:1; }","duration":"129.742022ms","start":"2026-05-09T05:32:14.75661Z","end":"2026-05-09T05:32:14.886352Z","steps":["trace[1182520812] 'process raft request' (duration: 80.237829ms)","trace[1182520812] 'compare' (duration: 49.016043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:32:14.886499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.567888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:32:14.886575Z","caller":"traceutil/trace.go:171","msg":"trace[989797141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10275; }","duration":"113.67516ms","start":"2026-05-09T05:32:14.77288Z","end":"2026-05-09T05:32:14.886555Z","steps":["trace[989797141] 'agreement among raft nodes before linearized reading' (duration: 113.537467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:32:14.886889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.895622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-db-init-62fjc\" ","response":"range_response_count:1 size:7350"} {"level":"info","ts":"2026-05-09T05:32:14.886996Z","caller":"traceutil/trace.go:171","msg":"trace[376620224] range","detail":"{range_begin:/registry/pods/openstack/staffeln-db-init-62fjc; range_end:; response_count:1; response_revision:10275; }","duration":"119.046766ms","start":"2026-05-09T05:32:14.767923Z","end":"2026-05-09T05:32:14.886969Z","steps":["trace[376620224] 'agreement among raft nodes before linearized reading' (duration: 118.716378ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:33:21.784499Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8286} {"level":"info","ts":"2026-05-09T05:33:21.86082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8286,"took":"73.941153ms","hash":2984178026,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":26599424,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-09T05:33:21.860873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2984178026,"revision":8286,"compact-revision":6110} {"level":"info","ts":"2026-05-09T05:38:21.790841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10975} {"level":"info","ts":"2026-05-09T05:38:21.86968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10975,"took":"76.361842ms","hash":3062123511,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":27836416,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T05:38:21.869754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3062123511,"revision":10975,"compact-revision":8286} {"level":"warn","ts":"2026-05-09T05:40:05.949784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.680206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-09T05:40:05.94987Z","caller":"traceutil/trace.go:171","msg":"trace[1412812456] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:13671; }","duration":"169.812359ms","start":"2026-05-09T05:40:05.780039Z","end":"2026-05-09T05:40:05.949851Z","steps":["trace[1412812456] 'range keys from in-memory index tree' (duration: 169.493371ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:40:15.1411Z","caller":"traceutil/trace.go:171","msg":"trace[1525138116] transaction","detail":"{read_only:false; response_revision:13837; number_of_response:1; }","duration":"297.35045ms","start":"2026-05-09T05:40:14.843721Z","end":"2026-05-09T05:40:15.141072Z","steps":["trace[1525138116] 'process raft request' (duration: 297.201097ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:40:15.15222Z","caller":"traceutil/trace.go:171","msg":"trace[83392517] linearizableReadLoop","detail":"{readStateIndex:14307; appliedIndex:14305; }","duration":"225.226312ms","start":"2026-05-09T05:40:14.926963Z","end":"2026-05-09T05:40:15.152189Z","steps":["trace[83392517] 'read index received' (duration: 214.061845ms)","trace[83392517] 'applied index is now lower than readState.Index' (duration: 11.162997ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:40:15.152334Z","caller":"traceutil/trace.go:171","msg":"trace[1389711206] transaction","detail":"{read_only:false; response_revision:13838; number_of_response:1; }","duration":"300.122779ms","start":"2026-05-09T05:40:14.852174Z","end":"2026-05-09T05:40:15.152297Z","steps":["trace[1389711206] 'process raft request' (duration: 299.727541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:40:15.152528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.546989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-05-09T05:40:15.152589Z","caller":"traceutil/trace.go:171","msg":"trace[611658826] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:13838; }","duration":"225.670631ms","start":"2026-05-09T05:40:14.926905Z","end":"2026-05-09T05:40:15.152576Z","steps":["trace[611658826] 'agreement among raft nodes before linearized reading' (duration: 225.444117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:40:15.152436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.282186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T05:40:15.152628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:40:14.852148Z","time spent":"300.389484ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:40:15.152654Z","caller":"traceutil/trace.go:171","msg":"trace[1083017666] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:13838; }","duration":"177.550742ms","start":"2026-05-09T05:40:14.975082Z","end":"2026-05-09T05:40:15.152632Z","steps":["trace[1083017666] 'agreement among raft nodes before linearized reading' (duration: 177.278137ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:41:54.364213Z","caller":"traceutil/trace.go:171","msg":"trace[949736979] linearizableReadLoop","detail":"{readStateIndex:15424; appliedIndex:15423; }","duration":"152.86328ms","start":"2026-05-09T05:41:54.211326Z","end":"2026-05-09T05:41:54.364189Z","steps":["trace[949736979] 'read index received' (duration: 152.678016ms)","trace[949736979] 'applied index is now lower than readState.Index' (duration: 184.554µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:41:54.364284Z","caller":"traceutil/trace.go:171","msg":"trace[1026625200] transaction","detail":"{read_only:false; response_revision:14922; number_of_response:1; }","duration":"217.06036ms","start":"2026-05-09T05:41:54.147189Z","end":"2026-05-09T05:41:54.36425Z","steps":["trace[1026625200] 'process raft request' (duration: 216.863676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:41:54.364444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.166942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T05:41:54.364483Z","caller":"traceutil/trace.go:171","msg":"trace[356172349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14922; }","duration":"149.242003ms","start":"2026-05-09T05:41:54.215228Z","end":"2026-05-09T05:41:54.36447Z","steps":["trace[356172349] 'agreement among raft nodes before linearized reading' (duration: 149.10199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:41:54.364536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.188006ms","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-05-09T05:41:54.364632Z","caller":"traceutil/trace.go:171","msg":"trace[1132951727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14922; }","duration":"153.326069ms","start":"2026-05-09T05:41:54.211286Z","end":"2026-05-09T05:41:54.364612Z","steps":["trace[1132951727] 'agreement among raft nodes before linearized reading' (duration: 153.042053ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:41:54.610275Z","caller":"traceutil/trace.go:171","msg":"trace[1592041526] linearizableReadLoop","detail":"{readStateIndex:15426; appliedIndex:15424; }","duration":"134.345097ms","start":"2026-05-09T05:41:54.475911Z","end":"2026-05-09T05:41:54.610256Z","steps":["trace[1592041526] 'read index received' (duration: 67.678515ms)","trace[1592041526] 'applied index is now lower than readState.Index' (duration: 66.665962ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:41:54.610564Z","caller":"traceutil/trace.go:171","msg":"trace[1715042794] transaction","detail":"{read_only:false; response_revision:14924; number_of_response:1; }","duration":"228.170616ms","start":"2026-05-09T05:41:54.382372Z","end":"2026-05-09T05:41:54.610542Z","steps":["trace[1715042794] 'process raft request' (duration: 227.852568ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:41:54.610567Z","caller":"traceutil/trace.go:171","msg":"trace[1607640857] transaction","detail":"{read_only:false; response_revision:14923; number_of_response:1; }","duration":"228.368019ms","start":"2026-05-09T05:41:54.382161Z","end":"2026-05-09T05:41:54.610529Z","steps":["trace[1607640857] 'process raft request' (duration: 161.325479ms)","trace[1607640857] 'compare' (duration: 66.61475ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:41:54.610651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.116481ms","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-05-09T05:41:54.610671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.770326ms","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-05-09T05:41:54.610721Z","caller":"traceutil/trace.go:171","msg":"trace[705727339] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14924; }","duration":"134.883288ms","start":"2026-05-09T05:41:54.475825Z","end":"2026-05-09T05:41:54.610709Z","steps":["trace[705727339] 'agreement among raft nodes before linearized reading' (duration: 134.709815ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:41:54.610726Z","caller":"traceutil/trace.go:171","msg":"trace[1989391991] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14924; }","duration":"134.190523ms","start":"2026-05-09T05:41:54.476483Z","end":"2026-05-09T05:41:54.610673Z","steps":["trace[1989391991] 'agreement among raft nodes before linearized reading' (duration: 134.115252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:41:54.610744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.009426ms","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-05-09T05:41:54.61082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.159582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T05:41:54.61085Z","caller":"traceutil/trace.go:171","msg":"trace[327806881] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14924; }","duration":"131.176529ms","start":"2026-05-09T05:41:54.479644Z","end":"2026-05-09T05:41:54.610821Z","steps":["trace[327806881] 'agreement among raft nodes before linearized reading' (duration: 130.964785ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:41:54.610907Z","caller":"traceutil/trace.go:171","msg":"trace[662805449] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:14924; }","duration":"134.313666ms","start":"2026-05-09T05:41:54.476569Z","end":"2026-05-09T05:41:54.610883Z","steps":["trace[662805449] 'agreement among raft nodes before linearized reading' (duration: 134.05819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:41:54.610915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.863983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-09T05:41:54.611007Z","caller":"traceutil/trace.go:171","msg":"trace[246891734] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14924; }","duration":"130.980886ms","start":"2026-05-09T05:41:54.479998Z","end":"2026-05-09T05:41:54.610979Z","steps":["trace[246891734] 'agreement among raft nodes before linearized reading' (duration: 130.671719ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:43:21.79687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13008} {"level":"info","ts":"2026-05-09T05:43:21.875392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13008,"took":"75.838198ms","hash":4020930605,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T05:43:21.875448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4020930605,"revision":13008,"compact-revision":10975} {"level":"info","ts":"2026-05-09T05:47:38.144026Z","caller":"traceutil/trace.go:171","msg":"trace[2114569420] transaction","detail":"{read_only:false; response_revision:17721; number_of_response:1; }","duration":"167.894106ms","start":"2026-05-09T05:47:37.976092Z","end":"2026-05-09T05:47:38.143986Z","steps":["trace[2114569420] 'process raft request' (duration: 167.657671ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:47:38.150403Z","caller":"traceutil/trace.go:171","msg":"trace[1420428219] linearizableReadLoop","detail":"{readStateIndex:18311; appliedIndex:18309; }","duration":"132.878672ms","start":"2026-05-09T05:47:38.017495Z","end":"2026-05-09T05:47:38.150374Z","steps":["trace[1420428219] 'read index received' (duration: 126.278192ms)","trace[1420428219] 'applied index is now lower than readState.Index' (duration: 6.59934ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:47:38.150473Z","caller":"traceutil/trace.go:171","msg":"trace[1552623368] transaction","detail":"{read_only:false; response_revision:17722; number_of_response:1; }","duration":"166.617499ms","start":"2026-05-09T05:47:37.983803Z","end":"2026-05-09T05:47:38.150421Z","steps":["trace[1552623368] 'process raft request' (duration: 166.410514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:47:38.150675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.137077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T05:47:38.150776Z","caller":"traceutil/trace.go:171","msg":"trace[1478871113] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:17722; }","duration":"133.305531ms","start":"2026-05-09T05:47:38.017446Z","end":"2026-05-09T05:47:38.150752Z","steps":["trace[1478871113] 'agreement among raft nodes before linearized reading' (duration: 133.086306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:47:38.825334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.268828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:47:38.825433Z","caller":"traceutil/trace.go:171","msg":"trace[125365361] linearizableReadLoop","detail":"{readStateIndex:18313; appliedIndex:18312; }","duration":"288.946957ms","start":"2026-05-09T05:47:38.53647Z","end":"2026-05-09T05:47:38.825417Z","steps":["trace[125365361] 'read index received' (duration: 41.091µs)","trace[125365361] 'applied index is now lower than readState.Index' (duration: 288.904896ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:47:38.825828Z","caller":"traceutil/trace.go:171","msg":"trace[1911975360] transaction","detail":"{read_only:false; response_revision:17724; number_of_response:1; }","duration":"541.916789ms","start":"2026-05-09T05:47:38.283895Z","end":"2026-05-09T05:47:38.825812Z","steps":["trace[1911975360] 'process raft request' (duration: 79.097619ms)","trace[1911975360] 'compare' (duration: 461.243577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:47:38.825907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:47:38.283875Z","time spent":"541.995382ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:47:38.826399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.922258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5mzs9\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-05-09T05:47:38.826472Z","caller":"traceutil/trace.go:171","msg":"trace[1771577639] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5mzs9; range_end:; response_count:1; response_revision:17724; }","duration":"290.0046ms","start":"2026-05-09T05:47:38.536427Z","end":"2026-05-09T05:47:38.826432Z","steps":["trace[1771577639] 'agreement among raft nodes before linearized reading' (duration: 289.855807ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:48:21.802952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15506} {"level":"info","ts":"2026-05-09T05:48:21.896632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15506,"took":"85.70688ms","hash":4186706094,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":33943552,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-09T05:48:21.896678Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4186706094,"revision":15506,"compact-revision":13008} {"level":"info","ts":"2026-05-09T05:51:01.940916Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T05:51:01.947356Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T05:51:01.947456Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T05:52:31.360402Z","caller":"traceutil/trace.go:171","msg":"trace[504716953] transaction","detail":"{read_only:false; response_revision:19843; number_of_response:1; }","duration":"147.192751ms","start":"2026-05-09T05:52:31.213183Z","end":"2026-05-09T05:52:31.360376Z","steps":["trace[504716953] 'process raft request' (duration: 147.029988ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:52:31.431268Z","caller":"traceutil/trace.go:171","msg":"trace[856266467] transaction","detail":"{read_only:false; response_revision:19844; number_of_response:1; }","duration":"214.678271ms","start":"2026-05-09T05:52:31.216562Z","end":"2026-05-09T05:52:31.431241Z","steps":["trace[856266467] 'process raft request' (duration: 214.553059ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:52:31.95339Z","caller":"traceutil/trace.go:171","msg":"trace[465813579] transaction","detail":"{read_only:false; response_revision:19845; number_of_response:1; }","duration":"125.792887ms","start":"2026-05-09T05:52:31.827574Z","end":"2026-05-09T05:52:31.953367Z","steps":["trace[465813579] 'process raft request' (duration: 125.650944ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:52:32.406128Z","caller":"traceutil/trace.go:171","msg":"trace[145689357] transaction","detail":"{read_only:false; response_revision:19846; number_of_response:1; }","duration":"126.641035ms","start":"2026-05-09T05:52:32.279457Z","end":"2026-05-09T05:52:32.406098Z","steps":["trace[145689357] 'process raft request' (duration: 126.471642ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:52:32.406416Z","caller":"traceutil/trace.go:171","msg":"trace[1889515014] linearizableReadLoop","detail":"{readStateIndex:20512; appliedIndex:20512; }","duration":"117.734756ms","start":"2026-05-09T05:52:32.288595Z","end":"2026-05-09T05:52:32.40633Z","steps":["trace[1889515014] 'read index received' (duration: 117.723216ms)","trace[1889515014] 'applied index is now lower than readState.Index' (duration: 9.45µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:52:32.406736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.116055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:52:32.406826Z","caller":"traceutil/trace.go:171","msg":"trace[705520936] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19846; }","duration":"118.239328ms","start":"2026-05-09T05:52:32.288553Z","end":"2026-05-09T05:52:32.406792Z","steps":["trace[705520936] 'agreement among raft nodes before linearized reading' (duration: 118.064064ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:52:32.713812Z","caller":"traceutil/trace.go:171","msg":"trace[117924184] linearizableReadLoop","detail":"{readStateIndex:20513; appliedIndex:20512; }","duration":"307.203863ms","start":"2026-05-09T05:52:32.406564Z","end":"2026-05-09T05:52:32.713768Z","steps":["trace[117924184] 'read index received' (duration: 215.96597ms)","trace[117924184] 'applied index is now lower than readState.Index' (duration: 91.236433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:52:32.713972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.362696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T05:52:32.714043Z","caller":"traceutil/trace.go:171","msg":"trace[842034575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19847; }","duration":"356.463469ms","start":"2026-05-09T05:52:32.357562Z","end":"2026-05-09T05:52:32.714026Z","steps":["trace[842034575] 'agreement among raft nodes before linearized reading' (duration: 356.243934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:52:32.714036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.450571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-09T05:52:32.714093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:52:32.357554Z","time spent":"356.53129ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:52:32.714119Z","caller":"traceutil/trace.go:171","msg":"trace[1624590032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19847; }","duration":"359.565263ms","start":"2026-05-09T05:52:32.354531Z","end":"2026-05-09T05:52:32.714097Z","steps":["trace[1624590032] 'agreement among raft nodes before linearized reading' (duration: 359.288918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:52:32.714099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.072768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-09T05:52:32.714163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:52:32.35452Z","time spent":"359.634996ms","remote":"127.0.0.1:55308","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":"info","ts":"2026-05-09T05:52:32.714192Z","caller":"traceutil/trace.go:171","msg":"trace[2129234589] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19847; }","duration":"354.19642ms","start":"2026-05-09T05:52:32.359971Z","end":"2026-05-09T05:52:32.714167Z","steps":["trace[2129234589] 'agreement among raft nodes before linearized reading' (duration: 353.822012ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:52:32.71424Z","caller":"traceutil/trace.go:171","msg":"trace[983687754] transaction","detail":"{read_only:false; response_revision:19847; number_of_response:1; }","duration":"409.790079ms","start":"2026-05-09T05:52:32.304435Z","end":"2026-05-09T05:52:32.714225Z","steps":["trace[983687754] 'process raft request' (duration: 318.118566ms)","trace[983687754] 'compare' (duration: 90.842966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:52:32.714089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.79623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-09T05:52:32.714252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:52:32.359962Z","time spent":"354.280782ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T05:52:32.714284Z","caller":"traceutil/trace.go:171","msg":"trace[2134562324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19847; }","duration":"361.044835ms","start":"2026-05-09T05:52:32.353229Z","end":"2026-05-09T05:52:32.714274Z","steps":["trace[2134562324] 'agreement among raft nodes before linearized reading' (duration: 360.708448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:52:32.714327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:52:32.353207Z","time spent":"361.108787ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:52:32.714324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:52:32.304389Z","time spent":"409.881441ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:52:32.714143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.294222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:52:32.714382Z","caller":"traceutil/trace.go:171","msg":"trace[780170040] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:19847; }","duration":"304.567267ms","start":"2026-05-09T05:52:32.409805Z","end":"2026-05-09T05:52:32.714372Z","steps":["trace[780170040] 'agreement among raft nodes before linearized reading' (duration: 304.316032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:52:32.714411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:52:32.409781Z","time spent":"304.622879ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-09T05:53:21.809092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18114} {"level":"info","ts":"2026-05-09T05:53:21.918206Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18114,"took":"105.299423ms","hash":2340060720,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30248960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T05:53:21.918295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2340060720,"revision":18114,"compact-revision":15506} {"level":"info","ts":"2026-05-09T05:56:12.4768Z","caller":"traceutil/trace.go:171","msg":"trace[2080540945] transaction","detail":"{read_only:false; response_revision:22211; number_of_response:1; }","duration":"182.901889ms","start":"2026-05-09T05:56:12.293865Z","end":"2026-05-09T05:56:12.476767Z","steps":["trace[2080540945] 'process raft request' (duration: 170.009375ms)","trace[2080540945] 'compare' (duration: 12.424473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:56:12.870203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.758242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:623"} {"level":"info","ts":"2026-05-09T05:56:12.871584Z","caller":"traceutil/trace.go:171","msg":"trace[284145231] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22211; }","duration":"205.050728ms","start":"2026-05-09T05:56:12.665358Z","end":"2026-05-09T05:56:12.870408Z","steps":["trace[284145231] 'range keys from in-memory index tree' (duration: 204.555387ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:34.464944Z","caller":"traceutil/trace.go:171","msg":"trace[115589233] transaction","detail":"{read_only:false; response_revision:23081; number_of_response:1; }","duration":"110.268469ms","start":"2026-05-09T05:57:34.354648Z","end":"2026-05-09T05:57:34.464916Z","steps":["trace[115589233] 'process raft request' (duration: 110.067285ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:34.592409Z","caller":"traceutil/trace.go:171","msg":"trace[1898651212] transaction","detail":"{read_only:false; response_revision:23083; number_of_response:1; }","duration":"182.707574ms","start":"2026-05-09T05:57:34.409673Z","end":"2026-05-09T05:57:34.59238Z","steps":["trace[1898651212] 'process raft request' (duration: 182.616572ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:34.592571Z","caller":"traceutil/trace.go:171","msg":"trace[1387609851] transaction","detail":"{read_only:false; response_revision:23082; number_of_response:1; }","duration":"234.381089ms","start":"2026-05-09T05:57:34.358146Z","end":"2026-05-09T05:57:34.592527Z","steps":["trace[1387609851] 'process raft request' (duration: 190.343836ms)","trace[1387609851] 'compare' (duration: 43.638235ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:57:34.592617Z","caller":"traceutil/trace.go:171","msg":"trace[1063097381] transaction","detail":"{read_only:false; response_revision:23084; number_of_response:1; }","duration":"133.165893ms","start":"2026-05-09T05:57:34.459422Z","end":"2026-05-09T05:57:34.592588Z","steps":["trace[1063097381] 'process raft request' (duration: 132.919868ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:34.592573Z","caller":"traceutil/trace.go:171","msg":"trace[1361712591] linearizableReadLoop","detail":"{readStateIndex:23841; appliedIndex:23838; }","duration":"166.50618ms","start":"2026-05-09T05:57:34.426045Z","end":"2026-05-09T05:57:34.592551Z","steps":["trace[1361712591] 'read index received' (duration: 38.808163ms)","trace[1361712591] 'applied index is now lower than readState.Index' (duration: 127.696267ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:57:34.592694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.627913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:57:34.592735Z","caller":"traceutil/trace.go:171","msg":"trace[806227901] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:23084; }","duration":"166.724835ms","start":"2026-05-09T05:57:34.426Z","end":"2026-05-09T05:57:34.592725Z","steps":["trace[806227901] 'agreement among raft nodes before linearized reading' (duration: 166.632873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:57:34.592833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.168243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:57:34.592882Z","caller":"traceutil/trace.go:171","msg":"trace[1681362465] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:23084; }","duration":"166.250525ms","start":"2026-05-09T05:57:34.42662Z","end":"2026-05-09T05:57:34.59287Z","steps":["trace[1681362465] 'agreement among raft nodes before linearized reading' (duration: 166.158713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:57:34.592877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.887408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T05:57:34.592909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.590362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-05-09T05:57:34.592946Z","caller":"traceutil/trace.go:171","msg":"trace[1502560275] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:23084; }","duration":"165.98891ms","start":"2026-05-09T05:57:34.426937Z","end":"2026-05-09T05:57:34.592926Z","steps":["trace[1502560275] 'agreement among raft nodes before linearized reading' (duration: 165.852796ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:57:34.592976Z","caller":"traceutil/trace.go:171","msg":"trace[922807452] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:23084; }","duration":"165.671524ms","start":"2026-05-09T05:57:34.42729Z","end":"2026-05-09T05:57:34.592961Z","steps":["trace[922807452] 'agreement among raft nodes before linearized reading' (duration: 165.50425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:57:34.592889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.581001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:57:34.593113Z","caller":"traceutil/trace.go:171","msg":"trace[1691170902] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:23084; }","duration":"165.820767ms","start":"2026-05-09T05:57:34.42725Z","end":"2026-05-09T05:57:34.593071Z","steps":["trace[1691170902] 'agreement among raft nodes before linearized reading' (duration: 165.53429ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:58:21.814484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20373} {"level":"info","ts":"2026-05-09T05:58:21.91648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20373,"took":"98.687063ms","hash":3820207620,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":42471424,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-09T05:58:21.916536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3820207620,"revision":20373,"compact-revision":18114} {"level":"info","ts":"2026-05-09T05:59:26.055011Z","caller":"traceutil/trace.go:171","msg":"trace[1233064042] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"138.019037ms","start":"2026-05-09T05:59:25.916953Z","end":"2026-05-09T05:59:26.054972Z","steps":["trace[1233064042] 'process raft request' (duration: 137.70224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:26.702938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.804829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:26.703117Z","caller":"traceutil/trace.go:171","msg":"trace[1922401601] linearizableReadLoop","detail":"{readStateIndex:24971; appliedIndex:24970; }","duration":"133.982571ms","start":"2026-05-09T05:59:26.569107Z","end":"2026-05-09T05:59:26.70309Z","steps":["trace[1922401601] 'read index received' (duration: 46.261µs)","trace[1922401601] 'applied index is now lower than readState.Index' (duration: 133.93434ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:26.703233Z","caller":"traceutil/trace.go:171","msg":"trace[780217714] transaction","detail":"{read_only:false; response_revision:24181; number_of_response:1; }","duration":"176.220247ms","start":"2026-05-09T05:59:26.526997Z","end":"2026-05-09T05:59:26.703217Z","steps":["trace[780217714] 'process raft request' (duration: 24.02239ms)","trace[780217714] 'compare' (duration: 151.492472ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:26.703834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.725867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-09T05:59:26.703876Z","caller":"traceutil/trace.go:171","msg":"trace[1194370678] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24181; }","duration":"134.801609ms","start":"2026-05-09T05:59:26.569064Z","end":"2026-05-09T05:59:26.703865Z","steps":["trace[1194370678] 'agreement among raft nodes before linearized reading' (duration: 134.622746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:26.703832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.835602ms","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-05-09T05:59:26.703976Z","caller":"traceutil/trace.go:171","msg":"trace[2101300508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24181; }","duration":"129.026096ms","start":"2026-05-09T05:59:26.574933Z","end":"2026-05-09T05:59:26.703959Z","steps":["trace[2101300508] 'agreement among raft nodes before linearized reading' (duration: 128.461634ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:26.860721Z","caller":"traceutil/trace.go:171","msg":"trace[299150837] linearizableReadLoop","detail":"{readStateIndex:24972; appliedIndex:24971; }","duration":"148.218133ms","start":"2026-05-09T05:59:26.712444Z","end":"2026-05-09T05:59:26.860663Z","steps":["trace[299150837] 'read index received' (duration: 135.280948ms)","trace[299150837] 'applied index is now lower than readState.Index' (duration: 12.936095ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:26.860808Z","caller":"traceutil/trace.go:171","msg":"trace[623202486] transaction","detail":"{read_only:false; response_revision:24182; number_of_response:1; }","duration":"152.485114ms","start":"2026-05-09T05:59:26.708273Z","end":"2026-05-09T05:59:26.860758Z","steps":["trace[623202486] 'process raft request' (duration: 139.465277ms)","trace[623202486] 'compare' (duration: 12.667719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:26.86171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.246254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-09T05:59:26.861768Z","caller":"traceutil/trace.go:171","msg":"trace[1377875274] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24182; }","duration":"149.337097ms","start":"2026-05-09T05:59:26.712417Z","end":"2026-05-09T05:59:26.861754Z","steps":["trace[1377875274] 'agreement among raft nodes before linearized reading' (duration: 148.439238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:27.154178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.313563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:27.154339Z","caller":"traceutil/trace.go:171","msg":"trace[1112077376] transaction","detail":"{read_only:false; response_revision:24183; number_of_response:1; }","duration":"288.135798ms","start":"2026-05-09T05:59:26.866171Z","end":"2026-05-09T05:59:27.154307Z","steps":["trace[1112077376] 'process raft request' (duration: 148.56187ms)","trace[1112077376] 'compare' (duration: 139.005397ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:27.18873Z","caller":"traceutil/trace.go:171","msg":"trace[224360619] linearizableReadLoop","detail":"{readStateIndex:24974; appliedIndex:24972; }","duration":"321.199231ms","start":"2026-05-09T05:59:26.867506Z","end":"2026-05-09T05:59:27.188705Z","steps":["trace[224360619] 'read index received' (duration: 147.338584ms)","trace[224360619] 'applied index is now lower than readState.Index' (duration: 173.859457ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:27.189092Z","caller":"traceutil/trace.go:171","msg":"trace[839104086] transaction","detail":"{read_only:false; response_revision:24184; number_of_response:1; }","duration":"321.698121ms","start":"2026-05-09T05:59:26.867383Z","end":"2026-05-09T05:59:27.189081Z","steps":["trace[839104086] 'process raft request' (duration: 321.092899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:27.189219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:26.867362Z","time spent":"321.764612ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:27.189339Z","caller":"traceutil/trace.go:171","msg":"trace[427544709] transaction","detail":"{read_only:false; response_revision:24185; number_of_response:1; }","duration":"219.028344ms","start":"2026-05-09T05:59:26.970299Z","end":"2026-05-09T05:59:27.189328Z","steps":["trace[427544709] 'process raft request' (duration: 218.320969ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:27.189421Z","caller":"traceutil/trace.go:171","msg":"trace[1733338795] transaction","detail":"{read_only:false; response_revision:24186; number_of_response:1; }","duration":"208.810028ms","start":"2026-05-09T05:59:26.980604Z","end":"2026-05-09T05:59:27.189414Z","steps":["trace[1733338795] 'process raft request' (duration: 208.063113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:27.189622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.112229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T05:59:27.189659Z","caller":"traceutil/trace.go:171","msg":"trace[621445838] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:24186; }","duration":"322.176511ms","start":"2026-05-09T05:59:26.867474Z","end":"2026-05-09T05:59:27.18965Z","steps":["trace[621445838] 'agreement among raft nodes before linearized reading' (duration: 322.013268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:27.189688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:26.867467Z","time spent":"322.213922ms","remote":"127.0.0.1:55380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":182,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-09T05:59:27.316086Z","caller":"traceutil/trace.go:171","msg":"trace[1259665367] linearizableReadLoop","detail":"{readStateIndex:24977; appliedIndex:24976; }","duration":"127.252218ms","start":"2026-05-09T05:59:27.188806Z","end":"2026-05-09T05:59:27.316058Z","steps":["trace[1259665367] 'read index received' (duration: 1.272126ms)","trace[1259665367] 'applied index is now lower than readState.Index' (duration: 125.978772ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:27.316104Z","caller":"traceutil/trace.go:171","msg":"trace[1588797125] transaction","detail":"{read_only:false; response_revision:24187; number_of_response:1; }","duration":"142.808588ms","start":"2026-05-09T05:59:27.173265Z","end":"2026-05-09T05:59:27.316073Z","steps":["trace[1588797125] 'process raft request' (duration: 126.518262ms)","trace[1588797125] 'compare' (duration: 16.148213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:27.316255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.410658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T05:59:27.316327Z","caller":"traceutil/trace.go:171","msg":"trace[2098717889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24187; }","duration":"332.533392ms","start":"2026-05-09T05:59:26.983778Z","end":"2026-05-09T05:59:27.316311Z","steps":["trace[2098717889] 'agreement among raft nodes before linearized reading' (duration: 332.291157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:27.316371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:26.983764Z","time spent":"332.599023ms","remote":"127.0.0.1:55308","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-05-09T05:59:27.316425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.39467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-09T05:59:27.316485Z","caller":"traceutil/trace.go:171","msg":"trace[1648311638] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24187; }","duration":"101.548583ms","start":"2026-05-09T05:59:27.214917Z","end":"2026-05-09T05:59:27.316466Z","steps":["trace[1648311638] 'agreement among raft nodes before linearized reading' (duration: 101.355779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:27.316584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.124754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7749"} {"level":"info","ts":"2026-05-09T05:59:27.316787Z","caller":"traceutil/trace.go:171","msg":"trace[1181962711] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:24187; }","duration":"417.200286ms","start":"2026-05-09T05:59:26.899415Z","end":"2026-05-09T05:59:27.316615Z","steps":["trace[1181962711] 'agreement among raft nodes before linearized reading' (duration: 416.746377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:27.31691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:26.899396Z","time spent":"417.438631ms","remote":"127.0.0.1:42400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7771,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-05-09T05:59:31.486879Z","caller":"traceutil/trace.go:171","msg":"trace[190449067] transaction","detail":"{read_only:false; response_revision:24224; number_of_response:1; }","duration":"120.639867ms","start":"2026-05-09T05:59:31.366212Z","end":"2026-05-09T05:59:31.486851Z","steps":["trace[190449067] 'process raft request' (duration: 120.488704ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:31.690473Z","caller":"traceutil/trace.go:171","msg":"trace[1196682744] transaction","detail":"{read_only:false; response_revision:24225; number_of_response:1; }","duration":"321.885165ms","start":"2026-05-09T05:59:31.368552Z","end":"2026-05-09T05:59:31.690437Z","steps":["trace[1196682744] 'process raft request' (duration: 315.524209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:31.690621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:31.368525Z","time spent":"322.030248ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:31.697881Z","caller":"traceutil/trace.go:171","msg":"trace[1218440970] transaction","detail":"{read_only:false; response_revision:24226; number_of_response:1; }","duration":"157.607061ms","start":"2026-05-09T05:59:31.54024Z","end":"2026-05-09T05:59:31.697847Z","steps":["trace[1218440970] 'process raft request' (duration: 157.354906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:32.216648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.858823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T05:59:32.216728Z","caller":"traceutil/trace.go:171","msg":"trace[1269854968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24228; }","duration":"137.994136ms","start":"2026-05-09T05:59:32.078717Z","end":"2026-05-09T05:59:32.216711Z","steps":["trace[1269854968] 'range keys from in-memory index tree' (duration: 137.679219ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:32.653694Z","caller":"traceutil/trace.go:171","msg":"trace[2018755178] transaction","detail":"{read_only:false; response_revision:24230; number_of_response:1; }","duration":"229.897785ms","start":"2026-05-09T05:59:32.42377Z","end":"2026-05-09T05:59:32.653668Z","steps":["trace[2018755178] 'process raft request' (duration: 229.739622ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:32.830298Z","caller":"traceutil/trace.go:171","msg":"trace[486042652] transaction","detail":"{read_only:false; response_revision:24231; number_of_response:1; }","duration":"106.580831ms","start":"2026-05-09T05:59:32.723685Z","end":"2026-05-09T05:59:32.830266Z","steps":["trace[486042652] 'process raft request' (duration: 104.624169ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:33.082045Z","caller":"traceutil/trace.go:171","msg":"trace[1503274748] linearizableReadLoop","detail":"{readStateIndex:25023; appliedIndex:25022; }","duration":"136.465213ms","start":"2026-05-09T05:59:32.945543Z","end":"2026-05-09T05:59:33.082008Z","steps":["trace[1503274748] 'read index received' (duration: 103.712709ms)","trace[1503274748] 'applied index is now lower than readState.Index' (duration: 32.751004ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:33.082188Z","caller":"traceutil/trace.go:171","msg":"trace[1983066839] transaction","detail":"{read_only:false; response_revision:24232; number_of_response:1; }","duration":"182.655143ms","start":"2026-05-09T05:59:32.899519Z","end":"2026-05-09T05:59:33.082174Z","steps":["trace[1983066839] 'process raft request' (duration: 149.698665ms)","trace[1983066839] 'compare' (duration: 32.495729ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:33.082253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.681507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:33.082339Z","caller":"traceutil/trace.go:171","msg":"trace[1611475804] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24232; }","duration":"136.83198ms","start":"2026-05-09T05:59:32.945486Z","end":"2026-05-09T05:59:33.082318Z","steps":["trace[1611475804] 'agreement among raft nodes before linearized reading' (duration: 136.690727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.082443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.537305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T05:59:33.082741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.318164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T05:59:33.082767Z","caller":"traceutil/trace.go:171","msg":"trace[988828299] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:24232; }","duration":"137.012085ms","start":"2026-05-09T05:59:32.945719Z","end":"2026-05-09T05:59:33.082731Z","steps":["trace[988828299] 'agreement among raft nodes before linearized reading' (duration: 136.480723ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:33.082791Z","caller":"traceutil/trace.go:171","msg":"trace[1056115044] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:24232; }","duration":"113.395605ms","start":"2026-05-09T05:59:32.969385Z","end":"2026-05-09T05:59:33.08278Z","steps":["trace[1056115044] 'agreement among raft nodes before linearized reading' (duration: 113.317063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.388861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.326416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-09T05:59:33.388936Z","caller":"traceutil/trace.go:171","msg":"trace[888498441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24232; }","duration":"191.442479ms","start":"2026-05-09T05:59:33.197471Z","end":"2026-05-09T05:59:33.388914Z","steps":["trace[888498441] 'range keys from in-memory index tree' (duration: 191.174813ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:33.390012Z","caller":"traceutil/trace.go:171","msg":"trace[1806609334] linearizableReadLoop","detail":"{readStateIndex:25025; appliedIndex:25023; }","duration":"172.719482ms","start":"2026-05-09T05:59:33.217283Z","end":"2026-05-09T05:59:33.390002Z","steps":["trace[1806609334] 'read index received' (duration: 142.519232ms)","trace[1806609334] 'applied index is now lower than readState.Index' (duration: 30.19953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:33.390111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.841164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T05:59:33.390136Z","caller":"traceutil/trace.go:171","msg":"trace[1912150400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24234; }","duration":"172.921186ms","start":"2026-05-09T05:59:33.217208Z","end":"2026-05-09T05:59:33.390129Z","steps":["trace[1912150400] 'agreement among raft nodes before linearized reading' (duration: 172.834455ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:33.390199Z","caller":"traceutil/trace.go:171","msg":"trace[724535297] transaction","detail":"{read_only:false; response_revision:24233; number_of_response:1; }","duration":"179.304932ms","start":"2026-05-09T05:59:33.210825Z","end":"2026-05-09T05:59:33.39013Z","steps":["trace[724535297] 'process raft request' (duration: 148.813366ms)","trace[724535297] 'compare' (duration: 29.72506ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:33.390243Z","caller":"traceutil/trace.go:171","msg":"trace[870352402] transaction","detail":"{read_only:false; response_revision:24234; number_of_response:1; }","duration":"177.889822ms","start":"2026-05-09T05:59:33.212311Z","end":"2026-05-09T05:59:33.390201Z","steps":["trace[870352402] 'process raft request' (duration: 177.654817ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:33.853347Z","caller":"traceutil/trace.go:171","msg":"trace[1237499362] transaction","detail":"{read_only:false; response_revision:24235; number_of_response:1; }","duration":"459.561334ms","start":"2026-05-09T05:59:33.393759Z","end":"2026-05-09T05:59:33.85332Z","steps":["trace[1237499362] 'process raft request' (duration: 459.414391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.853582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.393696Z","time spent":"459.801749ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:33.870372Z","caller":"traceutil/trace.go:171","msg":"trace[1696366136] transaction","detail":"{read_only:false; response_revision:24237; number_of_response:1; }","duration":"167.038812ms","start":"2026-05-09T05:59:33.703297Z","end":"2026-05-09T05:59:33.870336Z","steps":["trace[1696366136] 'process raft request' (duration: 166.92579ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:33.870569Z","caller":"traceutil/trace.go:171","msg":"trace[623523537] linearizableReadLoop","detail":"{readStateIndex:25027; appliedIndex:25025; }","duration":"435.634418ms","start":"2026-05-09T05:59:33.43488Z","end":"2026-05-09T05:59:33.870515Z","steps":["trace[623523537] 'read index received' (duration: 418.26013ms)","trace[623523537] 'applied index is now lower than readState.Index' (duration: 17.370928ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:33.870588Z","caller":"traceutil/trace.go:171","msg":"trace[1617102177] transaction","detail":"{read_only:false; response_revision:24238; number_of_response:1; }","duration":"166.856249ms","start":"2026-05-09T05:59:33.703696Z","end":"2026-05-09T05:59:33.870552Z","steps":["trace[1617102177] 'process raft request' (duration: 166.581843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.870828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.941964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-09T05:59:33.870912Z","caller":"traceutil/trace.go:171","msg":"trace[15428446] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24238; }","duration":"436.065707ms","start":"2026-05-09T05:59:33.434825Z","end":"2026-05-09T05:59:33.870891Z","steps":["trace[15428446] 'agreement among raft nodes before linearized reading' (duration: 435.872483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.870965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.434804Z","time spent":"436.148408ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-05-09T05:59:33.871076Z","caller":"traceutil/trace.go:171","msg":"trace[1551146801] transaction","detail":"{read_only:false; response_revision:24236; number_of_response:1; }","duration":"476.487844ms","start":"2026-05-09T05:59:33.393904Z","end":"2026-05-09T05:59:33.870392Z","steps":["trace[1551146801] 'process raft request' (duration: 476.174467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.871227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.393889Z","time spent":"477.230979ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:33.880292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.5093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T05:59:33.880356Z","caller":"traceutil/trace.go:171","msg":"trace[507926593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24239; }","duration":"390.632853ms","start":"2026-05-09T05:59:33.489709Z","end":"2026-05-09T05:59:33.880342Z","steps":["trace[507926593] 'agreement among raft nodes before linearized reading' (duration: 390.467219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.880346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.383906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T05:59:33.880385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.489687Z","time spent":"390.692213ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T05:59:33.880409Z","caller":"traceutil/trace.go:171","msg":"trace[508289773] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24239; }","duration":"306.502489ms","start":"2026-05-09T05:59:33.573888Z","end":"2026-05-09T05:59:33.88039Z","steps":["trace[508289773] 'agreement among raft nodes before linearized reading' (duration: 306.389046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:33.880459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.573867Z","time spent":"306.58229ms","remote":"127.0.0.1:41508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:59:33.880551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.890011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-09T05:59:33.880644Z","caller":"traceutil/trace.go:171","msg":"trace[292835635] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24239; }","duration":"185.026883ms","start":"2026-05-09T05:59:33.695597Z","end":"2026-05-09T05:59:33.880624Z","steps":["trace[292835635] 'agreement among raft nodes before linearized reading' (duration: 184.655035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:34.034696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.032027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:34.03502Z","caller":"traceutil/trace.go:171","msg":"trace[2025703308] transaction","detail":"{read_only:false; response_revision:24240; number_of_response:1; }","duration":"157.95191ms","start":"2026-05-09T05:59:33.876934Z","end":"2026-05-09T05:59:34.034886Z","steps":["trace[2025703308] 'process raft request' (duration: 26.609865ms)","trace[2025703308] 'compare' (duration: 130.857024ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:34.317819Z","caller":"traceutil/trace.go:171","msg":"trace[263248017] transaction","detail":"{read_only:false; response_revision:24243; number_of_response:1; }","duration":"432.822928ms","start":"2026-05-09T05:59:33.884964Z","end":"2026-05-09T05:59:34.317787Z","steps":["trace[263248017] 'process raft request' (duration: 432.748196ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:34.31785Z","caller":"traceutil/trace.go:171","msg":"trace[1843004015] transaction","detail":"{read_only:false; response_revision:24242; number_of_response:1; }","duration":"433.779748ms","start":"2026-05-09T05:59:33.884041Z","end":"2026-05-09T05:59:34.31782Z","steps":["trace[1843004015] 'process raft request' (duration: 433.599053ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:34.317821Z","caller":"traceutil/trace.go:171","msg":"trace[473936056] transaction","detail":"{read_only:false; response_revision:24241; number_of_response:1; }","duration":"433.813768ms","start":"2026-05-09T05:59:33.883956Z","end":"2026-05-09T05:59:34.31777Z","steps":["trace[473936056] 'process raft request' (duration: 340.99834ms)","trace[473936056] 'compare' (duration: 92.514121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:34.31799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.883994Z","time spent":"433.923831ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:34.31799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.884861Z","time spent":"433.041482ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:34.318076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.883937Z","time spent":"434.008822ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:34.328396Z","caller":"traceutil/trace.go:171","msg":"trace[107309140] transaction","detail":"{read_only:false; response_revision:24244; number_of_response:1; }","duration":"336.525726ms","start":"2026-05-09T05:59:33.991848Z","end":"2026-05-09T05:59:34.328374Z","steps":["trace[107309140] 'process raft request' (duration: 336.396553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:34.328514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:33.99181Z","time spent":"336.626437ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:34.471841Z","caller":"traceutil/trace.go:171","msg":"trace[1272404256] transaction","detail":"{read_only:false; response_revision:24245; number_of_response:1; }","duration":"139.62423ms","start":"2026-05-09T05:59:34.332179Z","end":"2026-05-09T05:59:34.471803Z","steps":["trace[1272404256] 'process raft request' (duration: 134.480442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:34.933773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.133132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-05-09T05:59:34.933856Z","caller":"traceutil/trace.go:171","msg":"trace[266846680] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:24246; }","duration":"100.257135ms","start":"2026-05-09T05:59:34.833573Z","end":"2026-05-09T05:59:34.93383Z","steps":["trace[266846680] 'range keys from in-memory index tree' (duration: 99.953049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:35.272257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.622592ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-09T05:59:35.272751Z","caller":"traceutil/trace.go:171","msg":"trace[839905001] linearizableReadLoop","detail":"{readStateIndex:25039; appliedIndex:25038; }","duration":"185.392781ms","start":"2026-05-09T05:59:35.087344Z","end":"2026-05-09T05:59:35.272737Z","steps":["trace[839905001] 'read index received' (duration: 35.256138ms)","trace[839905001] 'applied index is now lower than readState.Index' (duration: 150.135473ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:35.272887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.545084ms","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-05-09T05:59:35.272906Z","caller":"traceutil/trace.go:171","msg":"trace[1103834364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24247; }","duration":"185.614346ms","start":"2026-05-09T05:59:35.087286Z","end":"2026-05-09T05:59:35.2729Z","steps":["trace[1103834364] 'agreement among raft nodes before linearized reading' (duration: 185.522524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:35.587135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.748812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:35.587264Z","caller":"traceutil/trace.go:171","msg":"trace[833761947] transaction","detail":"{read_only:false; response_revision:24250; number_of_response:1; }","duration":"191.745956ms","start":"2026-05-09T05:59:35.395504Z","end":"2026-05-09T05:59:35.58725Z","steps":["trace[833761947] 'process raft request' (duration: 36.81611ms)","trace[833761947] 'compare' (duration: 154.64927ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:35.587625Z","caller":"traceutil/trace.go:171","msg":"trace[352665811] transaction","detail":"{read_only:false; response_revision:24251; number_of_response:1; }","duration":"191.806518ms","start":"2026-05-09T05:59:35.395778Z","end":"2026-05-09T05:59:35.587584Z","steps":["trace[352665811] 'process raft request' (duration: 191.4478ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:36.109588Z","caller":"traceutil/trace.go:171","msg":"trace[905326645] linearizableReadLoop","detail":"{readStateIndex:25044; appliedIndex:25043; }","duration":"234.720937ms","start":"2026-05-09T05:59:35.87484Z","end":"2026-05-09T05:59:36.109561Z","steps":["trace[905326645] 'read index received' (duration: 234.618445ms)","trace[905326645] 'applied index is now lower than readState.Index' (duration: 101.202µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:36.109667Z","caller":"traceutil/trace.go:171","msg":"trace[1803981011] transaction","detail":"{read_only:false; response_revision:24252; number_of_response:1; }","duration":"248.09199ms","start":"2026-05-09T05:59:35.861526Z","end":"2026-05-09T05:59:36.109618Z","steps":["trace[1803981011] 'process raft request' (duration: 247.828444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:36.109894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.042674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-09T05:59:36.109983Z","caller":"traceutil/trace.go:171","msg":"trace[1739467130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24252; }","duration":"235.187897ms","start":"2026-05-09T05:59:35.874772Z","end":"2026-05-09T05:59:36.10996Z","steps":["trace[1739467130] 'agreement among raft nodes before linearized reading' (duration: 234.929911ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:36.12696Z","caller":"traceutil/trace.go:171","msg":"trace[899253909] transaction","detail":"{read_only:false; response_revision:24253; number_of_response:1; }","duration":"251.407401ms","start":"2026-05-09T05:59:35.875515Z","end":"2026-05-09T05:59:36.126922Z","steps":["trace[899253909] 'process raft request' (duration: 251.140355ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:36.127362Z","caller":"traceutil/trace.go:171","msg":"trace[2028380085] transaction","detail":"{read_only:false; response_revision:24254; number_of_response:1; }","duration":"251.720907ms","start":"2026-05-09T05:59:35.875603Z","end":"2026-05-09T05:59:36.127324Z","steps":["trace[2028380085] 'process raft request' (duration: 251.258807ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:36.127443Z","caller":"traceutil/trace.go:171","msg":"trace[943825466] transaction","detail":"{read_only:false; response_revision:24255; number_of_response:1; }","duration":"244.180758ms","start":"2026-05-09T05:59:35.883237Z","end":"2026-05-09T05:59:36.127417Z","steps":["trace[943825466] 'process raft request' (duration: 243.930423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:36.127517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.720781ms","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-05-09T05:59:36.127608Z","caller":"traceutil/trace.go:171","msg":"trace[1835600259] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24255; }","duration":"145.873384ms","start":"2026-05-09T05:59:35.981717Z","end":"2026-05-09T05:59:36.127591Z","steps":["trace[1835600259] 'agreement among raft nodes before linearized reading' (duration: 145.609238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:36.464273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.475164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-09T05:59:36.46439Z","caller":"traceutil/trace.go:171","msg":"trace[1566229477] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24256; }","duration":"244.621958ms","start":"2026-05-09T05:59:36.219736Z","end":"2026-05-09T05:59:36.464358Z","steps":["trace[1566229477] 'range keys from in-memory index tree' (duration: 244.2481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:36.464507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.664114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-09T05:59:36.464623Z","caller":"traceutil/trace.go:171","msg":"trace[1412212987] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24256; }","duration":"141.880729ms","start":"2026-05-09T05:59:36.322712Z","end":"2026-05-09T05:59:36.464592Z","steps":["trace[1412212987] 'range keys from in-memory index tree' (duration: 141.45577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:36.465306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.483821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T05:59:36.465407Z","caller":"traceutil/trace.go:171","msg":"trace[1934643963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24256; }","duration":"142.609834ms","start":"2026-05-09T05:59:36.322772Z","end":"2026-05-09T05:59:36.465382Z","steps":["trace[1934643963] 'range keys from in-memory index tree' (duration: 141.361108ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:36.636133Z","caller":"traceutil/trace.go:171","msg":"trace[2104644325] transaction","detail":"{read_only:false; response_revision:24257; number_of_response:1; }","duration":"166.294355ms","start":"2026-05-09T05:59:36.469805Z","end":"2026-05-09T05:59:36.6361Z","steps":["trace[2104644325] 'process raft request' (duration: 166.078771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:36.818921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.071777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:36.819332Z","caller":"traceutil/trace.go:171","msg":"trace[660129735] linearizableReadLoop","detail":"{readStateIndex:25050; appliedIndex:25048; }","duration":"344.240218ms","start":"2026-05-09T05:59:36.475059Z","end":"2026-05-09T05:59:36.819299Z","steps":["trace[660129735] 'read index received' (duration: 160.867931ms)","trace[660129735] 'applied index is now lower than readState.Index' (duration: 183.368247ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:36.819427Z","caller":"traceutil/trace.go:171","msg":"trace[525056371] transaction","detail":"{read_only:false; response_revision:24258; number_of_response:1; }","duration":"349.53796ms","start":"2026-05-09T05:59:36.469864Z","end":"2026-05-09T05:59:36.819402Z","steps":["trace[525056371] 'process raft request' (duration: 202.884922ms)","trace[525056371] 'compare' (duration: 145.856882ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:36.819564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.055219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T05:59:36.819561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.495424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T05:59:36.819611Z","caller":"traceutil/trace.go:171","msg":"trace[2001148748] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24259; }","duration":"156.155272ms","start":"2026-05-09T05:59:36.663443Z","end":"2026-05-09T05:59:36.819599Z","steps":["trace[2001148748] 'agreement among raft nodes before linearized reading' (duration: 156.049409ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:36.819622Z","caller":"traceutil/trace.go:171","msg":"trace[2095814899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24259; }","duration":"344.577216ms","start":"2026-05-09T05:59:36.47503Z","end":"2026-05-09T05:59:36.819607Z","steps":["trace[2095814899] 'agreement among raft nodes before linearized reading' (duration: 344.407832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:36.819614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:36.469759Z","time spent":"349.758926ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:36.819651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:36.475011Z","time spent":"344.633838ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:59:37.165364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.870855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:37.165607Z","caller":"traceutil/trace.go:171","msg":"trace[1330610102] transaction","detail":"{read_only:false; response_revision:24261; number_of_response:1; }","duration":"226.454882ms","start":"2026-05-09T05:59:36.939131Z","end":"2026-05-09T05:59:37.165585Z","steps":["trace[1330610102] 'process raft request' (duration: 226.36227ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:37.166057Z","caller":"traceutil/trace.go:171","msg":"trace[1152428708] transaction","detail":"{read_only:false; response_revision:24260; number_of_response:1; }","duration":"341.45505ms","start":"2026-05-09T05:59:36.824537Z","end":"2026-05-09T05:59:37.165992Z","steps":["trace[1152428708] 'process raft request' (duration: 176.86029ms)","trace[1152428708] 'compare' (duration: 163.682571ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:37.166215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:36.824519Z","time spent":"341.613714ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:37.457253Z","caller":"traceutil/trace.go:171","msg":"trace[339609375] transaction","detail":"{read_only:false; response_revision:24262; number_of_response:1; }","duration":"112.022815ms","start":"2026-05-09T05:59:37.345202Z","end":"2026-05-09T05:59:37.457225Z","steps":["trace[339609375] 'process raft request' (duration: 111.803171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:37.90509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.331665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T05:59:37.905202Z","caller":"traceutil/trace.go:171","msg":"trace[2017733032] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:24262; }","duration":"372.512389ms","start":"2026-05-09T05:59:37.532663Z","end":"2026-05-09T05:59:37.905175Z","steps":["trace[2017733032] 'count revisions from in-memory index tree' (duration: 372.154882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:37.905258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:37.532633Z","time spent":"372.611831ms","remote":"127.0.0.1:47738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":75,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-05-09T05:59:37.906039Z","caller":"traceutil/trace.go:171","msg":"trace[2637288] transaction","detail":"{read_only:false; response_revision:24263; number_of_response:1; }","duration":"313.727442ms","start":"2026-05-09T05:59:37.592273Z","end":"2026-05-09T05:59:37.906Z","steps":["trace[2637288] 'process raft request' (duration: 309.86911ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:37.906233Z","caller":"traceutil/trace.go:171","msg":"trace[1203841801] linearizableReadLoop","detail":"{readStateIndex:25057; appliedIndex:25054; }","duration":"143.318468ms","start":"2026-05-09T05:59:37.762898Z","end":"2026-05-09T05:59:37.906216Z","steps":["trace[1203841801] 'read index received' (duration: 139.236762ms)","trace[1203841801] 'applied index is now lower than readState.Index' (duration: 4.080736ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:37.906265Z","caller":"traceutil/trace.go:171","msg":"trace[1147455583] transaction","detail":"{read_only:false; response_revision:24264; number_of_response:1; }","duration":"312.20403ms","start":"2026-05-09T05:59:37.594042Z","end":"2026-05-09T05:59:37.906246Z","steps":["trace[1147455583] 'process raft request' (duration: 312.000316ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:37.906345Z","caller":"traceutil/trace.go:171","msg":"trace[1955334226] transaction","detail":"{read_only:false; response_revision:24265; number_of_response:1; }","duration":"253.893323ms","start":"2026-05-09T05:59:37.652401Z","end":"2026-05-09T05:59:37.906295Z","steps":["trace[1955334226] 'process raft request' (duration: 253.770581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:37.906477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.548235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-766bc5db76-9lkvq.18adceb81183d71b\" ","response":"range_response_count:1 size:2108"} {"level":"warn","ts":"2026-05-09T05:59:37.906364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:37.594029Z","time spent":"312.276431ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:37.906523Z","caller":"traceutil/trace.go:171","msg":"trace[1768458971] range","detail":"{range_begin:/registry/events/openstack/glance-api-766bc5db76-9lkvq.18adceb81183d71b; range_end:; response_count:1; response_revision:24265; }","duration":"143.667238ms","start":"2026-05-09T05:59:37.762842Z","end":"2026-05-09T05:59:37.906509Z","steps":["trace[1768458971] 'agreement among raft nodes before linearized reading' (duration: 143.445602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:37.906226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:37.592236Z","time spent":"313.892616ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:38.207298Z","caller":"traceutil/trace.go:171","msg":"trace[1273608244] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"297.245062ms","start":"2026-05-09T05:59:37.910011Z","end":"2026-05-09T05:59:38.207256Z","steps":["trace[1273608244] 'process raft request' (duration: 262.102687ms)","trace[1273608244] 'compare' (duration: 34.959601ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:38.401402Z","caller":"traceutil/trace.go:171","msg":"trace[619707937] transaction","detail":"{read_only:false; response_revision:24271; number_of_response:1; }","duration":"189.353964ms","start":"2026-05-09T05:59:38.212024Z","end":"2026-05-09T05:59:38.401378Z","steps":["trace[619707937] 'process raft request' (duration: 189.183081ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:38.418561Z","caller":"traceutil/trace.go:171","msg":"trace[1960146832] linearizableReadLoop","detail":"{readStateIndex:25064; appliedIndex:25063; }","duration":"173.435728ms","start":"2026-05-09T05:59:38.245098Z","end":"2026-05-09T05:59:38.418534Z","steps":["trace[1960146832] 'read index received' (duration: 156.387117ms)","trace[1960146832] 'applied index is now lower than readState.Index' (duration: 17.047551ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:38.418618Z","caller":"traceutil/trace.go:171","msg":"trace[2117443611] transaction","detail":"{read_only:false; response_revision:24272; number_of_response:1; }","duration":"204.829993ms","start":"2026-05-09T05:59:38.213754Z","end":"2026-05-09T05:59:38.418584Z","steps":["trace[2117443611] 'process raft request' (duration: 204.612578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:38.418875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.716264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T05:59:38.418919Z","caller":"traceutil/trace.go:171","msg":"trace[1714576868] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24272; }","duration":"173.814976ms","start":"2026-05-09T05:59:38.245092Z","end":"2026-05-09T05:59:38.418907Z","steps":["trace[1714576868] 'agreement among raft nodes before linearized reading' (duration: 173.54884ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:38.785368Z","caller":"traceutil/trace.go:171","msg":"trace[440815355] transaction","detail":"{read_only:false; response_revision:24273; number_of_response:1; }","duration":"140.784705ms","start":"2026-05-09T05:59:38.644554Z","end":"2026-05-09T05:59:38.785339Z","steps":["trace[440815355] 'process raft request' (duration: 140.520589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:38.959652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.283909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T05:59:38.95974Z","caller":"traceutil/trace.go:171","msg":"trace[1819520481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24273; }","duration":"136.418333ms","start":"2026-05-09T05:59:38.823299Z","end":"2026-05-09T05:59:38.959717Z","steps":["trace[1819520481] 'range keys from in-memory index tree' (duration: 136.042995ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:38.95986Z","caller":"traceutil/trace.go:171","msg":"trace[1892413735] transaction","detail":"{read_only:false; response_revision:24274; number_of_response:1; }","duration":"134.257077ms","start":"2026-05-09T05:59:38.825563Z","end":"2026-05-09T05:59:38.959821Z","steps":["trace[1892413735] 'process raft request' (duration: 118.325979ms)","trace[1892413735] 'compare' (duration: 15.297634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:39.241661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.614254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:39.241872Z","caller":"traceutil/trace.go:171","msg":"trace[2074222764] transaction","detail":"{read_only:false; response_revision:24275; number_of_response:1; }","duration":"277.167498ms","start":"2026-05-09T05:59:38.96467Z","end":"2026-05-09T05:59:39.241838Z","steps":["trace[2074222764] 'process raft request' (duration: 134.292078ms)","trace[2074222764] 'compare' (duration: 142.43765ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:42.470188Z","caller":"traceutil/trace.go:171","msg":"trace[235589867] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"248.869457ms","start":"2026-05-09T05:59:42.2213Z","end":"2026-05-09T05:59:42.47017Z","steps":["trace[235589867] 'process raft request' (duration: 248.749724ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:42.768457Z","caller":"traceutil/trace.go:171","msg":"trace[1651909197] linearizableReadLoop","detail":"{readStateIndex:25092; appliedIndex:25089; }","duration":"354.158448ms","start":"2026-05-09T05:59:42.414276Z","end":"2026-05-09T05:59:42.768434Z","steps":["trace[1651909197] 'read index received' (duration: 55.801373ms)","trace[1651909197] 'applied index is now lower than readState.Index' (duration: 298.356075ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:42.768721Z","caller":"traceutil/trace.go:171","msg":"trace[276231047] transaction","detail":"{read_only:false; response_revision:24298; number_of_response:1; }","duration":"547.322285ms","start":"2026-05-09T05:59:42.221386Z","end":"2026-05-09T05:59:42.768708Z","steps":["trace[276231047] 'process raft request' (duration: 524.691196ms)","trace[276231047] 'compare' (duration: 21.812612ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:42.768819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.221361Z","time spent":"547.388376ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:42.768846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.124281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T05:59:42.768903Z","caller":"traceutil/trace.go:171","msg":"trace[231065113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24299; }","duration":"339.226773ms","start":"2026-05-09T05:59:42.429658Z","end":"2026-05-09T05:59:42.768885Z","steps":["trace[231065113] 'agreement among raft nodes before linearized reading' (duration: 339.061849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:42.768931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.429636Z","time spent":"339.289334ms","remote":"127.0.0.1:55308","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":"info","ts":"2026-05-09T05:59:42.768936Z","caller":"traceutil/trace.go:171","msg":"trace[631365687] transaction","detail":"{read_only:false; response_revision:24299; number_of_response:1; }","duration":"546.570719ms","start":"2026-05-09T05:59:42.222335Z","end":"2026-05-09T05:59:42.768906Z","steps":["trace[631365687] 'process raft request' (duration: 546.046748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:42.768985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.704051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-09T05:59:42.76902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.75189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:42.769032Z","caller":"traceutil/trace.go:171","msg":"trace[26574742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24299; }","duration":"354.784382ms","start":"2026-05-09T05:59:42.414235Z","end":"2026-05-09T05:59:42.769019Z","steps":["trace[26574742] 'agreement among raft nodes before linearized reading' (duration: 354.652409ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:42.769045Z","caller":"traceutil/trace.go:171","msg":"trace[1071779952] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24299; }","duration":"154.817682ms","start":"2026-05-09T05:59:42.614219Z","end":"2026-05-09T05:59:42.769037Z","steps":["trace[1071779952] 'agreement among raft nodes before linearized reading' (duration: 154.777571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:42.769074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.414216Z","time spent":"354.847664ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:59:42.769086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.222321Z","time spent":"546.680371ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:43.222152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.89247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:43.222401Z","caller":"traceutil/trace.go:171","msg":"trace[1776155529] linearizableReadLoop","detail":"{readStateIndex:25094; appliedIndex:25092; }","duration":"443.630606ms","start":"2026-05-09T05:59:42.778754Z","end":"2026-05-09T05:59:43.222385Z","steps":["trace[1776155529] 'read index received' (duration: 284.360049ms)","trace[1776155529] 'applied index is now lower than readState.Index' (duration: 159.269567ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:43.222623Z","caller":"traceutil/trace.go:171","msg":"trace[1239257931] transaction","detail":"{read_only:false; response_revision:24302; number_of_response:1; }","duration":"248.203942ms","start":"2026-05-09T05:59:42.974383Z","end":"2026-05-09T05:59:43.222587Z","steps":["trace[1239257931] 'process raft request' (duration: 247.964427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:43.222712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.977424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:43.22274Z","caller":"traceutil/trace.go:171","msg":"trace[135420301] transaction","detail":"{read_only:false; response_revision:24300; number_of_response:1; }","duration":"448.311946ms","start":"2026-05-09T05:59:42.774391Z","end":"2026-05-09T05:59:43.222703Z","steps":["trace[135420301] 'process raft request' (duration: 288.799014ms)","trace[135420301] 'compare' (duration: 158.617463ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:43.222808Z","caller":"traceutil/trace.go:171","msg":"trace[746371794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24302; }","duration":"444.098156ms","start":"2026-05-09T05:59:42.778697Z","end":"2026-05-09T05:59:43.222796Z","steps":["trace[746371794] 'agreement among raft nodes before linearized reading' (duration: 443.979473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:43.223034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.778687Z","time spent":"444.335841ms","remote":"127.0.0.1:55024","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-05-09T05:59:43.222889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.774376Z","time spent":"448.437489ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:43.222924Z","caller":"traceutil/trace.go:171","msg":"trace[916594402] transaction","detail":"{read_only:false; response_revision:24301; number_of_response:1; }","duration":"447.055529ms","start":"2026-05-09T05:59:42.775857Z","end":"2026-05-09T05:59:43.222913Z","steps":["trace[916594402] 'process raft request' (duration: 446.438656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:43.2232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.377036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-09T05:59:43.223321Z","caller":"traceutil/trace.go:171","msg":"trace[344278888] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24302; }","duration":"422.524578ms","start":"2026-05-09T05:59:42.800777Z","end":"2026-05-09T05:59:43.223301Z","steps":["trace[344278888] 'agreement among raft nodes before linearized reading' (duration: 422.225202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:43.223333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.775832Z","time spent":"447.421096ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:43.223434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:42.800762Z","time spent":"422.625581ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T05:59:43.491882Z","caller":"traceutil/trace.go:171","msg":"trace[456150012] linearizableReadLoop","detail":"{readStateIndex:25096; appliedIndex:25095; }","duration":"237.964016ms","start":"2026-05-09T05:59:43.253899Z","end":"2026-05-09T05:59:43.491863Z","steps":["trace[456150012] 'read index received' (duration: 237.741791ms)","trace[456150012] 'applied index is now lower than readState.Index' (duration: 221.415µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:43.491963Z","caller":"traceutil/trace.go:171","msg":"trace[218226799] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"262.767131ms","start":"2026-05-09T05:59:43.229169Z","end":"2026-05-09T05:59:43.491936Z","steps":["trace[218226799] 'process raft request' (duration: 262.378882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:43.49203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.113959ms","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-05-09T05:59:43.49205Z","caller":"traceutil/trace.go:171","msg":"trace[309284238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24303; }","duration":"238.16423ms","start":"2026-05-09T05:59:43.253879Z","end":"2026-05-09T05:59:43.492043Z","steps":["trace[309284238] 'agreement among raft nodes before linearized reading' (duration: 238.070778ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:43.492847Z","caller":"traceutil/trace.go:171","msg":"trace[1548818845] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"238.900886ms","start":"2026-05-09T05:59:43.253935Z","end":"2026-05-09T05:59:43.492836Z","steps":["trace[1548818845] 'process raft request' (duration: 238.665311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:43.492913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.90391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:43.492996Z","caller":"traceutil/trace.go:171","msg":"trace[2129980011] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24304; }","duration":"225.046822ms","start":"2026-05-09T05:59:43.267926Z","end":"2026-05-09T05:59:43.492973Z","steps":["trace[2129980011] 'agreement among raft nodes before linearized reading' (duration: 224.858358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:43.49301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.469546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T05:59:43.493069Z","caller":"traceutil/trace.go:171","msg":"trace[154671451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24304; }","duration":"138.536808ms","start":"2026-05-09T05:59:43.354516Z","end":"2026-05-09T05:59:43.493053Z","steps":["trace[154671451] 'agreement among raft nodes before linearized reading' (duration: 138.377925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:50.8439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.71048ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-09T05:59:51.119161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.200817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:51.119272Z","caller":"traceutil/trace.go:171","msg":"trace[697823254] linearizableReadLoop","detail":"{readStateIndex:25210; appliedIndex:25209; }","duration":"275.130674ms","start":"2026-05-09T05:59:50.844125Z","end":"2026-05-09T05:59:51.119256Z","steps":["trace[697823254] 'read index received' (duration: 106.709773ms)","trace[697823254] 'applied index is now lower than readState.Index' (duration: 168.419261ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:51.119299Z","caller":"traceutil/trace.go:171","msg":"trace[2140979562] transaction","detail":"{read_only:false; response_revision:24415; number_of_response:1; }","duration":"324.975971ms","start":"2026-05-09T05:59:50.794287Z","end":"2026-05-09T05:59:51.119263Z","steps":["trace[2140979562] 'process raft request' (duration: 156.592831ms)","trace[2140979562] 'compare' (duration: 167.390169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:51.119386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.72972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:51.11947Z","caller":"traceutil/trace.go:171","msg":"trace[873002641] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/services/; range_end:/registry/openstack.k-orc.cloud/services0; response_count:0; response_revision:24415; }","duration":"336.851663ms","start":"2026-05-09T05:59:50.7826Z","end":"2026-05-09T05:59:51.119451Z","steps":["trace[873002641] 'agreement among raft nodes before linearized reading' (duration: 336.72489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.119513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:50.782583Z","time spent":"336.919504ms","remote":"127.0.0.1:42952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/services/\" range_end:\"/registry/openstack.k-orc.cloud/services0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:59:51.119425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:50.794278Z","time spent":"325.080053ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:51.397851Z","caller":"traceutil/trace.go:171","msg":"trace[629917836] transaction","detail":"{read_only:false; response_revision:24416; number_of_response:1; }","duration":"182.817957ms","start":"2026-05-09T05:59:51.21501Z","end":"2026-05-09T05:59:51.397828Z","steps":["trace[629917836] 'process raft request' (duration: 182.674373ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:51.778942Z","caller":"traceutil/trace.go:171","msg":"trace[808682245] linearizableReadLoop","detail":"{readStateIndex:25212; appliedIndex:25210; }","duration":"520.353704ms","start":"2026-05-09T05:59:51.258564Z","end":"2026-05-09T05:59:51.778918Z","steps":["trace[808682245] 'read index received' (duration: 139.055468ms)","trace[808682245] 'applied index is now lower than readState.Index' (duration: 381.296846ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:51.779Z","caller":"traceutil/trace.go:171","msg":"trace[1540065280] transaction","detail":"{read_only:false; response_revision:24417; number_of_response:1; }","duration":"532.074812ms","start":"2026-05-09T05:59:51.246893Z","end":"2026-05-09T05:59:51.778968Z","steps":["trace[1540065280] 'process raft request' (duration: 531.834007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.779178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.605329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-09T05:59:51.779246Z","caller":"traceutil/trace.go:171","msg":"trace[258244066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24417; }","duration":"520.705111ms","start":"2026-05-09T05:59:51.25852Z","end":"2026-05-09T05:59:51.779225Z","steps":["trace[258244066] 'agreement among raft nodes before linearized reading' (duration: 520.526258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.779275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.258504Z","time spent":"520.765572ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T05:59:51.77916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.246873Z","time spent":"532.196695ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:51.965752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.198893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T05:59:51.965943Z","caller":"traceutil/trace.go:171","msg":"trace[1107726153] linearizableReadLoop","detail":"{readStateIndex:25214; appliedIndex:25212; }","duration":"186.893882ms","start":"2026-05-09T05:59:51.779032Z","end":"2026-05-09T05:59:51.965926Z","steps":["trace[1107726153] 'read index received' (duration: 37.428894ms)","trace[1107726153] 'applied index is now lower than readState.Index' (duration: 149.463908ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:51.966152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.616196ms","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-05-09T05:59:51.966009Z","caller":"traceutil/trace.go:171","msg":"trace[1698569327] transaction","detail":"{read_only:false; response_revision:24418; number_of_response:1; }","duration":"536.438965ms","start":"2026-05-09T05:59:51.429544Z","end":"2026-05-09T05:59:51.965983Z","steps":["trace[1698569327] 'process raft request' (duration: 386.922105ms)","trace[1698569327] 'compare' (duration: 148.940597ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:51.966211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.311728ms","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-05-09T05:59:51.966235Z","caller":"traceutil/trace.go:171","msg":"trace[1758241115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24419; }","duration":"435.79896ms","start":"2026-05-09T05:59:51.530419Z","end":"2026-05-09T05:59:51.966218Z","steps":["trace[1758241115] 'agreement among raft nodes before linearized reading' (duration: 435.529415ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:51.966285Z","caller":"traceutil/trace.go:171","msg":"trace[648591159] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24419; }","duration":"284.43188ms","start":"2026-05-09T05:59:51.681836Z","end":"2026-05-09T05:59:51.966268Z","steps":["trace[648591159] 'agreement among raft nodes before linearized reading' (duration: 284.232746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.96629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.530398Z","time spent":"435.881292ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T05:59:51.966273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.246866ms","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-05-09T05:59:51.966304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.429523Z","time spent":"536.74179ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:51.966352Z","caller":"traceutil/trace.go:171","msg":"trace[1782852657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24419; }","duration":"707.324678ms","start":"2026-05-09T05:59:51.259008Z","end":"2026-05-09T05:59:51.966333Z","steps":["trace[1782852657] 'agreement among raft nodes before linearized reading' (duration: 707.092213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.966074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.700006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-09T05:59:51.96643Z","caller":"traceutil/trace.go:171","msg":"trace[1039649742] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24419; }","duration":"592.064735ms","start":"2026-05-09T05:59:51.374352Z","end":"2026-05-09T05:59:51.966417Z","steps":["trace[1039649742] 'agreement among raft nodes before linearized reading' (duration: 591.605724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.966441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.259003Z","time spent":"707.410411ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T05:59:51.966501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.374312Z","time spent":"592.179087ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3258,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-09T05:59:51.966103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.464431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:51.96667Z","caller":"traceutil/trace.go:171","msg":"trace[1386137249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24419; }","duration":"193.061253ms","start":"2026-05-09T05:59:51.77358Z","end":"2026-05-09T05:59:51.966641Z","steps":["trace[1386137249] 'agreement among raft nodes before linearized reading' (duration: 192.46836ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:51.966128Z","caller":"traceutil/trace.go:171","msg":"trace[619245378] transaction","detail":"{read_only:false; response_revision:24419; number_of_response:1; }","duration":"448.588481ms","start":"2026-05-09T05:59:51.517535Z","end":"2026-05-09T05:59:51.966124Z","steps":["trace[619245378] 'process raft request' (duration: 448.318036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.966892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.517509Z","time spent":"449.324417ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T05:59:51.966124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.614846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T05:59:51.967091Z","caller":"traceutil/trace.go:171","msg":"trace[1432233278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24419; }","duration":"436.650779ms","start":"2026-05-09T05:59:51.530424Z","end":"2026-05-09T05:59:51.967075Z","steps":["trace[1432233278] 'agreement among raft nodes before linearized reading' (duration: 435.579176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.966159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.092087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:51.967174Z","caller":"traceutil/trace.go:171","msg":"trace[219233332] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24419; }","duration":"595.14421ms","start":"2026-05-09T05:59:51.372015Z","end":"2026-05-09T05:59:51.967159Z","steps":["trace[219233332] 'agreement among raft nodes before linearized reading' (duration: 594.117998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.967217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.371996Z","time spent":"595.208161ms","remote":"127.0.0.1:43284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-09T05:59:51.967192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.530406Z","time spent":"436.741881ms","remote":"127.0.0.1:55308","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-05-09T05:59:51.966152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.7684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T05:59:51.967366Z","caller":"traceutil/trace.go:171","msg":"trace[726829612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24419; }","duration":"437.019086ms","start":"2026-05-09T05:59:51.530332Z","end":"2026-05-09T05:59:51.967351Z","steps":["trace[726829612] 'agreement among raft nodes before linearized reading' (duration: 435.698298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.967409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.530318Z","time spent":"437.080568ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T05:59:51.966199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.275793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-09T05:59:51.967608Z","caller":"traceutil/trace.go:171","msg":"trace[106347647] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24419; }","duration":"414.711653ms","start":"2026-05-09T05:59:51.552886Z","end":"2026-05-09T05:59:51.967598Z","steps":["trace[106347647] 'agreement among raft nodes before linearized reading' (duration: 413.232121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.967644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.552875Z","time spent":"414.759254ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-09T05:59:51.966231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.908862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4254dfd3-9c81-4998-a164-ac23fa4e27bb\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-05-09T05:59:51.967723Z","caller":"traceutil/trace.go:171","msg":"trace[1784033752] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4254dfd3-9c81-4998-a164-ac23fa4e27bb; range_end:; response_count:1; response_revision:24419; }","duration":"619.433354ms","start":"2026-05-09T05:59:51.34828Z","end":"2026-05-09T05:59:51.967713Z","steps":["trace[1784033752] 'agreement among raft nodes before linearized reading' (duration: 617.86348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:51.967768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:51.348264Z","time spent":"619.488505ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4254dfd3-9c81-4998-a164-ac23fa4e27bb\" "} {"level":"info","ts":"2026-05-09T05:59:51.96817Z","caller":"traceutil/trace.go:171","msg":"trace[1865336036] transaction","detail":"{read_only:false; response_revision:24420; number_of_response:1; }","duration":"184.545103ms","start":"2026-05-09T05:59:51.78361Z","end":"2026-05-09T05:59:51.968155Z","steps":["trace[1865336036] 'process raft request' (duration: 184.442591ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:52.206253Z","caller":"traceutil/trace.go:171","msg":"trace[535852723] transaction","detail":"{read_only:false; response_revision:24423; number_of_response:1; }","duration":"233.407129ms","start":"2026-05-09T05:59:51.97281Z","end":"2026-05-09T05:59:52.206217Z","steps":["trace[535852723] 'process raft request' (duration: 217.932751ms)","trace[535852723] 'compare' (duration: 15.213092ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T05:59:52.206278Z","caller":"traceutil/trace.go:171","msg":"trace[1098594190] transaction","detail":"{read_only:false; response_revision:24424; number_of_response:1; }","duration":"233.439619ms","start":"2026-05-09T05:59:51.972813Z","end":"2026-05-09T05:59:52.206253Z","steps":["trace[1098594190] 'process raft request' (duration: 233.302817ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:52.2065Z","caller":"traceutil/trace.go:171","msg":"trace[585785653] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"233.660564ms","start":"2026-05-09T05:59:51.972816Z","end":"2026-05-09T05:59:52.206477Z","steps":["trace[585785653] 'process raft request' (duration: 233.392109ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:52.206795Z","caller":"traceutil/trace.go:171","msg":"trace[2085146111] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"232.958809ms","start":"2026-05-09T05:59:51.973816Z","end":"2026-05-09T05:59:52.206775Z","steps":["trace[2085146111] 'process raft request' (duration: 232.879678ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:52.206751Z","caller":"traceutil/trace.go:171","msg":"trace[1925534423] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"233.789066ms","start":"2026-05-09T05:59:51.972941Z","end":"2026-05-09T05:59:52.20673Z","steps":["trace[1925534423] 'process raft request' (duration: 233.451039ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:52.206786Z","caller":"traceutil/trace.go:171","msg":"trace[668768486] linearizableReadLoop","detail":"{readStateIndex:25222; appliedIndex:25217; }","duration":"228.860742ms","start":"2026-05-09T05:59:51.977906Z","end":"2026-05-09T05:59:52.206766Z","steps":["trace[668768486] 'read index received' (duration: 212.774632ms)","trace[668768486] 'applied index is now lower than readState.Index' (duration: 16.08477ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:52.206964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.042546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"warn","ts":"2026-05-09T05:59:52.207006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.879462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T05:59:52.207015Z","caller":"traceutil/trace.go:171","msg":"trace[412372213] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:24427; }","duration":"229.118057ms","start":"2026-05-09T05:59:51.977884Z","end":"2026-05-09T05:59:52.207002Z","steps":["trace[412372213] 'agreement among raft nodes before linearized reading' (duration: 228.948264ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:52.207038Z","caller":"traceutil/trace.go:171","msg":"trace[219155073] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24427; }","duration":"178.959264ms","start":"2026-05-09T05:59:52.028069Z","end":"2026-05-09T05:59:52.207029Z","steps":["trace[219155073] 'agreement among raft nodes before linearized reading' (duration: 178.888852ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:54.618963Z","caller":"traceutil/trace.go:171","msg":"trace[1092359172] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"105.473906ms","start":"2026-05-09T05:59:54.513455Z","end":"2026-05-09T05:59:54.618929Z","steps":["trace[1092359172] 'process raft request' (duration: 103.478804ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:58.115039Z","caller":"traceutil/trace.go:171","msg":"trace[1567746762] transaction","detail":"{read_only:false; response_revision:24481; number_of_response:1; }","duration":"318.38808ms","start":"2026-05-09T05:59:57.796625Z","end":"2026-05-09T05:59:58.115013Z","steps":["trace[1567746762] 'process raft request' (duration: 318.225166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T05:59:58.115202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T05:59:57.796604Z","time spent":"318.522093ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T05:59:58.119168Z","caller":"traceutil/trace.go:171","msg":"trace[16207405] linearizableReadLoop","detail":"{readStateIndex:25281; appliedIndex:25277; }","duration":"119.461983ms","start":"2026-05-09T05:59:57.999683Z","end":"2026-05-09T05:59:58.119145Z","steps":["trace[16207405] 'read index received' (duration: 115.224933ms)","trace[16207405] 'applied index is now lower than readState.Index' (duration: 4.2361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T05:59:58.119454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.748139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T05:59:58.119501Z","caller":"traceutil/trace.go:171","msg":"trace[591737896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24484; }","duration":"119.832251ms","start":"2026-05-09T05:59:57.999655Z","end":"2026-05-09T05:59:58.119487Z","steps":["trace[591737896] 'agreement among raft nodes before linearized reading' (duration: 119.585476ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:58.119977Z","caller":"traceutil/trace.go:171","msg":"trace[966516794] transaction","detail":"{read_only:false; response_revision:24482; number_of_response:1; }","duration":"128.644178ms","start":"2026-05-09T05:59:57.99132Z","end":"2026-05-09T05:59:58.119964Z","steps":["trace[966516794] 'process raft request' (duration: 127.635887ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:58.120243Z","caller":"traceutil/trace.go:171","msg":"trace[1170614500] transaction","detail":"{read_only:false; response_revision:24483; number_of_response:1; }","duration":"128.320771ms","start":"2026-05-09T05:59:57.991893Z","end":"2026-05-09T05:59:58.120214Z","steps":["trace[1170614500] 'process raft request' (duration: 127.183448ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T05:59:58.120431Z","caller":"traceutil/trace.go:171","msg":"trace[50467991] transaction","detail":"{read_only:false; response_revision:24484; number_of_response:1; }","duration":"126.239947ms","start":"2026-05-09T05:59:57.994178Z","end":"2026-05-09T05:59:58.120417Z","steps":["trace[50467991] 'process raft request' (duration: 124.934259ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:04.322847Z","caller":"traceutil/trace.go:171","msg":"trace[467241470] transaction","detail":"{read_only:false; response_revision:24578; number_of_response:1; }","duration":"263.052638ms","start":"2026-05-09T06:00:04.059768Z","end":"2026-05-09T06:00:04.322821Z","steps":["trace[467241470] 'process raft request' (duration: 262.874804ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:04.487975Z","caller":"traceutil/trace.go:171","msg":"trace[1179145073] transaction","detail":"{read_only:false; response_revision:24579; number_of_response:1; }","duration":"344.469355ms","start":"2026-05-09T06:00:04.143448Z","end":"2026-05-09T06:00:04.487918Z","steps":["trace[1179145073] 'process raft request' (duration: 344.153208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.488175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.14342Z","time spent":"344.661338ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:00:04.488309Z","caller":"traceutil/trace.go:171","msg":"trace[1088351068] transaction","detail":"{read_only:false; response_revision:24581; number_of_response:1; }","duration":"342.222466ms","start":"2026-05-09T06:00:04.146073Z","end":"2026-05-09T06:00:04.488295Z","steps":["trace[1088351068] 'process raft request' (duration: 342.032732ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:04.488273Z","caller":"traceutil/trace.go:171","msg":"trace[456641240] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"344.434673ms","start":"2026-05-09T06:00:04.143804Z","end":"2026-05-09T06:00:04.488238Z","steps":["trace[456641240] 'process raft request' (duration: 343.924933ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:04.488313Z","caller":"traceutil/trace.go:171","msg":"trace[838929697] linearizableReadLoop","detail":"{readStateIndex:25380; appliedIndex:25375; }","duration":"332.444798ms","start":"2026-05-09T06:00:04.155842Z","end":"2026-05-09T06:00:04.488287Z","steps":["trace[838929697] 'read index received' (duration: 166.850297ms)","trace[838929697] 'applied index is now lower than readState.Index' (duration: 165.592901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:04.488373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.146064Z","time spent":"342.282077ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:00:04.488448Z","caller":"traceutil/trace.go:171","msg":"trace[824409866] transaction","detail":"{read_only:false; response_revision:24582; number_of_response:1; }","duration":"340.06338ms","start":"2026-05-09T06:00:04.148368Z","end":"2026-05-09T06:00:04.488431Z","steps":["trace[824409866] 'process raft request' (duration: 339.853026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.488466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.143789Z","time spent":"344.579587ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:00:04.488646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.148352Z","time spent":"340.146452ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:00:04.488747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.890798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-09T06:00:04.488805Z","caller":"traceutil/trace.go:171","msg":"trace[1574660400] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24582; }","duration":"332.95426ms","start":"2026-05-09T06:00:04.155838Z","end":"2026-05-09T06:00:04.488792Z","steps":["trace[1574660400] 'agreement among raft nodes before linearized reading' (duration: 332.721015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.488844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.155811Z","time spent":"333.025122ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3258,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-09T06:00:04.575459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.040023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-09T06:00:04.575531Z","caller":"traceutil/trace.go:171","msg":"trace[1239525550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24583; }","duration":"400.123034ms","start":"2026-05-09T06:00:04.175391Z","end":"2026-05-09T06:00:04.575514Z","steps":["trace[1239525550] 'agreement among raft nodes before linearized reading' (duration: 399.93853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.575562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.175335Z","time spent":"400.220886ms","remote":"127.0.0.1:55308","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-05-09T06:00:04.575828Z","caller":"traceutil/trace.go:171","msg":"trace[1897985425] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"119.825881ms","start":"2026-05-09T06:00:04.455992Z","end":"2026-05-09T06:00:04.575818Z","steps":["trace[1897985425] 'process raft request' (duration: 119.230417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.575967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.436217ms","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-05-09T06:00:04.57599Z","caller":"traceutil/trace.go:171","msg":"trace[789752580] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24583; }","duration":"247.520738ms","start":"2026-05-09T06:00:04.328464Z","end":"2026-05-09T06:00:04.575984Z","steps":["trace[789752580] 'agreement among raft nodes before linearized reading' (duration: 247.457987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.576144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.765589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-09T06:00:04.576173Z","caller":"traceutil/trace.go:171","msg":"trace[866692592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24583; }","duration":"302.813151ms","start":"2026-05-09T06:00:04.273354Z","end":"2026-05-09T06:00:04.576167Z","steps":["trace[866692592] 'agreement among raft nodes before linearized reading' (duration: 302.730629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.57619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.273347Z","time spent":"302.838912ms","remote":"127.0.0.1:55308","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-05-09T06:00:04.576332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.165388ms","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-05-09T06:00:04.57636Z","caller":"traceutil/trace.go:171","msg":"trace[1693455166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24583; }","duration":"303.23246ms","start":"2026-05-09T06:00:04.273119Z","end":"2026-05-09T06:00:04.576352Z","steps":["trace[1693455166] 'agreement among raft nodes before linearized reading' (duration: 303.143078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.576378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.273102Z","time spent":"303.27211ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-09T06:00:04.576521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.545512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-09T06:00:04.576548Z","caller":"traceutil/trace.go:171","msg":"trace[143077177] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24583; }","duration":"308.590753ms","start":"2026-05-09T06:00:04.267949Z","end":"2026-05-09T06:00:04.57654Z","steps":["trace[143077177] 'agreement among raft nodes before linearized reading' (duration: 308.499721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.576565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.26794Z","time spent":"308.621284ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-09T06:00:04.576853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.90714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T06:00:04.576928Z","caller":"traceutil/trace.go:171","msg":"trace[291170076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24583; }","duration":"308.992292ms","start":"2026-05-09T06:00:04.267919Z","end":"2026-05-09T06:00:04.576911Z","steps":["trace[291170076] 'agreement among raft nodes before linearized reading' (duration: 308.759727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.576969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.267873Z","time spent":"309.088074ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T06:00:04.577207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.420354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T06:00:04.577243Z","caller":"traceutil/trace.go:171","msg":"trace[995346848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24583; }","duration":"311.491744ms","start":"2026-05-09T06:00:04.265742Z","end":"2026-05-09T06:00:04.577234Z","steps":["trace[995346848] 'agreement among raft nodes before linearized reading' (duration: 311.399572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:04.57727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:04.265722Z","time spent":"311.542686ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T06:00:09.811666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.204502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:09.811732Z","caller":"traceutil/trace.go:171","msg":"trace[1694417070] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:24649; }","duration":"115.317945ms","start":"2026-05-09T06:00:09.696398Z","end":"2026-05-09T06:00:09.811716Z","steps":["trace[1694417070] 'count revisions from in-memory index tree' (duration: 114.957377ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:10.509213Z","caller":"traceutil/trace.go:171","msg":"trace[1989546171] linearizableReadLoop","detail":"{readStateIndex:25449; appliedIndex:25448; }","duration":"344.588116ms","start":"2026-05-09T06:00:10.164586Z","end":"2026-05-09T06:00:10.509174Z","steps":["trace[1989546171] 'read index received' (duration: 275.181094ms)","trace[1989546171] 'applied index is now lower than readState.Index' (duration: 69.406342ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:10.509478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.873423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:10.509504Z","caller":"traceutil/trace.go:171","msg":"trace[754994493] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24649; }","duration":"344.937114ms","start":"2026-05-09T06:00:10.164559Z","end":"2026-05-09T06:00:10.509496Z","steps":["trace[754994493] 'agreement among raft nodes before linearized reading' (duration: 344.877983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:10.509527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:10.164542Z","time spent":"344.977795ms","remote":"127.0.0.1:42918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-05-09T06:00:10.511036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.759958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:10.511082Z","caller":"traceutil/trace.go:171","msg":"trace[2085819353] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24652; }","duration":"301.831689ms","start":"2026-05-09T06:00:10.209238Z","end":"2026-05-09T06:00:10.51107Z","steps":["trace[2085819353] 'agreement among raft nodes before linearized reading' (duration: 301.744217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:10.51111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:10.209225Z","time spent":"301.877861ms","remote":"127.0.0.1:43136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-09T06:00:17.990834Z","caller":"traceutil/trace.go:171","msg":"trace[1448673436] linearizableReadLoop","detail":"{readStateIndex:25528; appliedIndex:25527; }","duration":"137.796261ms","start":"2026-05-09T06:00:17.853013Z","end":"2026-05-09T06:00:17.990809Z","steps":["trace[1448673436] 'read index received' (duration: 137.623178ms)","trace[1448673436] 'applied index is now lower than readState.Index' (duration: 171.843µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:17.991136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.944704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:17.991228Z","caller":"traceutil/trace.go:171","msg":"trace[2110622642] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24726; }","duration":"138.240382ms","start":"2026-05-09T06:00:17.852976Z","end":"2026-05-09T06:00:17.991216Z","steps":["trace[2110622642] 'agreement among raft nodes before linearized reading' (duration: 137.930054ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:17.991686Z","caller":"traceutil/trace.go:171","msg":"trace[373261490] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"199.731846ms","start":"2026-05-09T06:00:17.791919Z","end":"2026-05-09T06:00:17.99165Z","steps":["trace[373261490] 'process raft request' (duration: 198.742044ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:18.300948Z","caller":"traceutil/trace.go:171","msg":"trace[722817971] linearizableReadLoop","detail":"{readStateIndex:25529; appliedIndex:25528; }","duration":"290.443378ms","start":"2026-05-09T06:00:18.010481Z","end":"2026-05-09T06:00:18.300925Z","steps":["trace[722817971] 'read index received' (duration: 290.241854ms)","trace[722817971] 'applied index is now lower than readState.Index' (duration: 200.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:18.301055Z","caller":"traceutil/trace.go:171","msg":"trace[1487712913] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"300.92122ms","start":"2026-05-09T06:00:18.000097Z","end":"2026-05-09T06:00:18.301018Z","steps":["trace[1487712913] 'process raft request' (duration: 300.661045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.301177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.669553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-09T06:00:18.301221Z","caller":"traceutil/trace.go:171","msg":"trace[1780473120] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24727; }","duration":"290.742665ms","start":"2026-05-09T06:00:18.010466Z","end":"2026-05-09T06:00:18.301208Z","steps":["trace[1780473120] 'agreement among raft nodes before linearized reading' (duration: 290.555311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.301286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:18.000085Z","time spent":"301.054003ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3217,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:00:18.702781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.506986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-05-09T06:00:18.702826Z","caller":"traceutil/trace.go:171","msg":"trace[2025684940] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"167.513962ms","start":"2026-05-09T06:00:18.535278Z","end":"2026-05-09T06:00:18.702792Z","steps":["trace[2025684940] 'process raft request' (duration: 135.133685ms)","trace[2025684940] 'compare' (duration: 31.929747ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:18.70287Z","caller":"traceutil/trace.go:171","msg":"trace[568843417] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24727; }","duration":"336.620078ms","start":"2026-05-09T06:00:18.366228Z","end":"2026-05-09T06:00:18.702848Z","steps":["trace[568843417] 'range keys from in-memory index tree' (duration: 336.466106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.702912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:18.366222Z","time spent":"336.680559ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3257,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-09T06:00:18.887847Z","caller":"traceutil/trace.go:171","msg":"trace[2091492492] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"343.946353ms","start":"2026-05-09T06:00:18.543869Z","end":"2026-05-09T06:00:18.887815Z","steps":["trace[2091492492] 'process raft request' (duration: 343.660917ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:18.887831Z","caller":"traceutil/trace.go:171","msg":"trace[2030227620] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"343.896852ms","start":"2026-05-09T06:00:18.543907Z","end":"2026-05-09T06:00:18.887803Z","steps":["trace[2030227620] 'process raft request' (duration: 343.817851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.888001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:18.543898Z","time spent":"344.030195ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T06:00:18.888013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:18.543858Z","time spent":"344.061146ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:00:18.888165Z","caller":"traceutil/trace.go:171","msg":"trace[1735723719] linearizableReadLoop","detail":"{readStateIndex:25533; appliedIndex:25529; }","duration":"233.275887ms","start":"2026-05-09T06:00:18.654853Z","end":"2026-05-09T06:00:18.888129Z","steps":["trace[1735723719] 'read index received' (duration: 15.530589ms)","trace[1735723719] 'applied index is now lower than readState.Index' (duration: 217.715287ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:18.888212Z","caller":"traceutil/trace.go:171","msg":"trace[358113750] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"344.087846ms","start":"2026-05-09T06:00:18.544079Z","end":"2026-05-09T06:00:18.888167Z","steps":["trace[358113750] 'process raft request' (duration: 343.690978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.888321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.471581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-09T06:00:18.888411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:18.544053Z","time spent":"344.26129ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:00:18.888508Z","caller":"traceutil/trace.go:171","msg":"trace[1067022697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24731; }","duration":"233.547403ms","start":"2026-05-09T06:00:18.654811Z","end":"2026-05-09T06:00:18.888359Z","steps":["trace[1067022697] 'agreement among raft nodes before linearized reading' (duration: 233.40952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.923573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.181168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T06:00:18.923685Z","caller":"traceutil/trace.go:171","msg":"trace[338062848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24733; }","duration":"262.310842ms","start":"2026-05-09T06:00:18.661348Z","end":"2026-05-09T06:00:18.923658Z","steps":["trace[338062848] 'agreement among raft nodes before linearized reading' (duration: 262.025886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.923841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.279302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-05-09T06:00:18.92392Z","caller":"traceutil/trace.go:171","msg":"trace[2102914153] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24733; }","duration":"213.394095ms","start":"2026-05-09T06:00:18.710505Z","end":"2026-05-09T06:00:18.923899Z","steps":["trace[2102914153] 'agreement among raft nodes before linearized reading' (duration: 213.213861ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:18.924193Z","caller":"traceutil/trace.go:171","msg":"trace[739569685] transaction","detail":"{read_only:false; response_revision:24732; number_of_response:1; }","duration":"225.866539ms","start":"2026-05-09T06:00:18.698308Z","end":"2026-05-09T06:00:18.924174Z","steps":["trace[739569685] 'process raft request' (duration: 224.793146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.92442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.768147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:18.924478Z","caller":"traceutil/trace.go:171","msg":"trace[379455951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24733; }","duration":"150.86365ms","start":"2026-05-09T06:00:18.773601Z","end":"2026-05-09T06:00:18.924464Z","steps":["trace[379455951] 'agreement among raft nodes before linearized reading' (duration: 150.774508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.92479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.063634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T06:00:18.924871Z","caller":"traceutil/trace.go:171","msg":"trace[454814097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24733; }","duration":"259.202657ms","start":"2026-05-09T06:00:18.665651Z","end":"2026-05-09T06:00:18.924854Z","steps":["trace[454814097] 'agreement among raft nodes before linearized reading' (duration: 259.021823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.925205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.534704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-09T06:00:18.92525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.673276ms","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-05-09T06:00:18.925277Z","caller":"traceutil/trace.go:171","msg":"trace[492991558] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24733; }","duration":"259.644696ms","start":"2026-05-09T06:00:18.665613Z","end":"2026-05-09T06:00:18.925257Z","steps":["trace[492991558] 'agreement among raft nodes before linearized reading' (duration: 259.484353ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:18.925309Z","caller":"traceutil/trace.go:171","msg":"trace[619575849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24733; }","duration":"259.755808ms","start":"2026-05-09T06:00:18.665541Z","end":"2026-05-09T06:00:18.925296Z","steps":["trace[619575849] 'agreement among raft nodes before linearized reading' (duration: 259.568984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:18.925314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.084556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T06:00:18.925386Z","caller":"traceutil/trace.go:171","msg":"trace[745618615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24733; }","duration":"261.189848ms","start":"2026-05-09T06:00:18.66418Z","end":"2026-05-09T06:00:18.92537Z","steps":["trace[745618615] 'agreement among raft nodes before linearized reading' (duration: 260.993254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:21.200697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.575968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T06:00:21.201003Z","caller":"traceutil/trace.go:171","msg":"trace[1524504265] linearizableReadLoop","detail":"{readStateIndex:25554; appliedIndex:25551; }","duration":"250.369428ms","start":"2026-05-09T06:00:20.950617Z","end":"2026-05-09T06:00:21.200987Z","steps":["trace[1524504265] 'read index received' (duration: 94.436753ms)","trace[1524504265] 'applied index is now lower than readState.Index' (duration: 155.931785ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:21.201107Z","caller":"traceutil/trace.go:171","msg":"trace[1241524850] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"302.33587ms","start":"2026-05-09T06:00:20.898759Z","end":"2026-05-09T06:00:21.201095Z","steps":["trace[1241524850] 'process raft request' (duration: 146.275902ms)","trace[1241524850] 'compare' (duration: 155.322073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:21.201178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:20.898728Z","time spent":"302.411612ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:00:21.201388Z","caller":"traceutil/trace.go:171","msg":"trace[1318286137] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"302.313939ms","start":"2026-05-09T06:00:20.899018Z","end":"2026-05-09T06:00:21.201331Z","steps":["trace[1318286137] 'process raft request' (duration: 301.783418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:21.201445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.822257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T06:00:21.201496Z","caller":"traceutil/trace.go:171","msg":"trace[1273381935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24752; }","duration":"250.900239ms","start":"2026-05-09T06:00:20.950579Z","end":"2026-05-09T06:00:21.20148Z","steps":["trace[1273381935] 'agreement among raft nodes before linearized reading' (duration: 250.737196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:21.20156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T06:00:20.899006Z","time spent":"302.466282ms","remote":"127.0.0.1:55308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T06:00:21.201559Z","caller":"traceutil/trace.go:171","msg":"trace[1402900024] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"268.040022ms","start":"2026-05-09T06:00:20.933297Z","end":"2026-05-09T06:00:21.201337Z","steps":["trace[1402900024] 'process raft request' (duration: 267.594683ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.201398Z","caller":"traceutil/trace.go:171","msg":"trace[865948792] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"183.024961ms","start":"2026-05-09T06:00:21.018088Z","end":"2026-05-09T06:00:21.201113Z","steps":["trace[865948792] 'process raft request' (duration: 182.846197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:21.202062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.320508ms","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-05-09T06:00:21.202067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.146734ms","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-05-09T06:00:21.202058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.832089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T06:00:21.20211Z","caller":"traceutil/trace.go:171","msg":"trace[267413076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24752; }","duration":"184.389929ms","start":"2026-05-09T06:00:21.017711Z","end":"2026-05-09T06:00:21.202101Z","steps":["trace[267413076] 'agreement among raft nodes before linearized reading' (duration: 184.225256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:21.202078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.635875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-09T06:00:21.202121Z","caller":"traceutil/trace.go:171","msg":"trace[403989366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24752; }","duration":"184.217716ms","start":"2026-05-09T06:00:21.017891Z","end":"2026-05-09T06:00:21.202109Z","steps":["trace[403989366] 'agreement among raft nodes before linearized reading' (duration: 184.075384ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.202139Z","caller":"traceutil/trace.go:171","msg":"trace[452807419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24752; }","duration":"184.926691ms","start":"2026-05-09T06:00:21.017194Z","end":"2026-05-09T06:00:21.20212Z","steps":["trace[452807419] 'agreement among raft nodes before linearized reading' (duration: 184.690406ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.202153Z","caller":"traceutil/trace.go:171","msg":"trace[1475104130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24752; }","duration":"184.742567ms","start":"2026-05-09T06:00:21.017388Z","end":"2026-05-09T06:00:21.202131Z","steps":["trace[1475104130] 'agreement among raft nodes before linearized reading' (duration: 184.568493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:21.202051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.842729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T06:00:21.20255Z","caller":"traceutil/trace.go:171","msg":"trace[2034710896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24752; }","duration":"185.35476ms","start":"2026-05-09T06:00:21.017163Z","end":"2026-05-09T06:00:21.202518Z","steps":["trace[2034710896] 'agreement among raft nodes before linearized reading' (duration: 184.813079ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.366473Z","caller":"traceutil/trace.go:171","msg":"trace[1790712903] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"161.330461ms","start":"2026-05-09T06:00:21.205109Z","end":"2026-05-09T06:00:21.36644Z","steps":["trace[1790712903] 'process raft request' (duration: 161.197228ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.3726Z","caller":"traceutil/trace.go:171","msg":"trace[394870172] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"166.316727ms","start":"2026-05-09T06:00:21.20626Z","end":"2026-05-09T06:00:21.372577Z","steps":["trace[394870172] 'process raft request' (duration: 166.179884ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.372877Z","caller":"traceutil/trace.go:171","msg":"trace[1228693668] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"165.705733ms","start":"2026-05-09T06:00:21.207164Z","end":"2026-05-09T06:00:21.372869Z","steps":["trace[1228693668] 'process raft request' (duration: 165.386967ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.372901Z","caller":"traceutil/trace.go:171","msg":"trace[238570969] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"149.587763ms","start":"2026-05-09T06:00:21.223295Z","end":"2026-05-09T06:00:21.372883Z","steps":["trace[238570969] 'process raft request' (duration: 149.551722ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.373064Z","caller":"traceutil/trace.go:171","msg":"trace[1242209254] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"165.214403ms","start":"2026-05-09T06:00:21.207842Z","end":"2026-05-09T06:00:21.373057Z","steps":["trace[1242209254] 'process raft request' (duration: 164.951958ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.373096Z","caller":"traceutil/trace.go:171","msg":"trace[929889410] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"164.662872ms","start":"2026-05-09T06:00:21.208425Z","end":"2026-05-09T06:00:21.373088Z","steps":["trace[929889410] 'process raft request' (duration: 164.397136ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:21.373206Z","caller":"traceutil/trace.go:171","msg":"trace[209884302] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"165.524809ms","start":"2026-05-09T06:00:21.207673Z","end":"2026-05-09T06:00:21.373198Z","steps":["trace[209884302] 'process raft request' (duration: 165.06961ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:25.071848Z","caller":"traceutil/trace.go:171","msg":"trace[1597543130] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"132.898776ms","start":"2026-05-09T06:00:24.938902Z","end":"2026-05-09T06:00:25.0718Z","steps":["trace[1597543130] 'process raft request' (duration: 132.651121ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:27.189124Z","caller":"traceutil/trace.go:171","msg":"trace[843389884] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"113.140987ms","start":"2026-05-09T06:00:27.075959Z","end":"2026-05-09T06:00:27.1891Z","steps":["trace[843389884] 'process raft request' (duration: 112.982184ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:27.421068Z","caller":"traceutil/trace.go:171","msg":"trace[1655576842] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"197.027486ms","start":"2026-05-09T06:00:27.224005Z","end":"2026-05-09T06:00:27.421033Z","steps":["trace[1655576842] 'process raft request' (duration: 114.140639ms)","trace[1655576842] 'compare' (duration: 82.474799ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T06:00:27.42121Z","caller":"traceutil/trace.go:171","msg":"trace[1144421012] transaction","detail":"{read_only:false; response_revision:24814; number_of_response:1; }","duration":"195.417962ms","start":"2026-05-09T06:00:27.225776Z","end":"2026-05-09T06:00:27.421194Z","steps":["trace[1144421012] 'process raft request' (duration: 195.190537ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:27.421085Z","caller":"traceutil/trace.go:171","msg":"trace[1280125468] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"195.32211ms","start":"2026-05-09T06:00:27.22574Z","end":"2026-05-09T06:00:27.421062Z","steps":["trace[1280125468] 'process raft request' (duration: 195.151396ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:00:27.421435Z","caller":"traceutil/trace.go:171","msg":"trace[1484452559] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"195.085985ms","start":"2026-05-09T06:00:27.226315Z","end":"2026-05-09T06:00:27.421401Z","steps":["trace[1484452559] 'process raft request' (duration: 194.788669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T06:00:30.433522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.513638ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-09T06:00:30.433684Z","caller":"traceutil/trace.go:171","msg":"trace[1517402290] linearizableReadLoop","detail":"{readStateIndex:25643; appliedIndex:25642; }","duration":"180.650588ms","start":"2026-05-09T06:00:30.253006Z","end":"2026-05-09T06:00:30.433657Z","steps":["trace[1517402290] 'read index received' (duration: 43.851µs)","trace[1517402290] 'applied index is now lower than readState.Index' (duration: 180.604997ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T06:00:30.433804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.76008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T06:00:30.433894Z","caller":"traceutil/trace.go:171","msg":"trace[629135166] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:24838; }","duration":"180.910213ms","start":"2026-05-09T06:00:30.25296Z","end":"2026-05-09T06:00:30.433871Z","steps":["trace[629135166] 'agreement among raft nodes before linearized reading' (duration: 180.73542ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T06:02:41.291092Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000693d.wal"} {"level":"info","ts":"2026-05-09T06:03:21.820108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23515} {"level":"info","ts":"2026-05-09T06:03:21.959407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23515,"took":"135.641424ms","hash":3278773012,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":37257216,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-09T06:03:21.959469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3278773012,"revision":23515,"compact-revision":20373} {"level":"info","ts":"2026-05-09T06:08:21.831743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26468} {"level":"info","ts":"2026-05-09T06:08:21.972756Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26468,"took":"137.022704ms","hash":2759615634,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":32555008,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T06:08:21.972823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2759615634,"revision":26468,"compact-revision":23515} {"level":"info","ts":"2026-05-09T06:08:33.226121Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cec112083e8d3ff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T06:08:33.233425Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T06:08:33.233506Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T06:13:21.844103Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29007} {"level":"info","ts":"2026-05-09T06:13:21.9803Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29007,"took":"132.896575ms","hash":3644571235,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30130176,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T06:13:21.980379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3644571235,"revision":29007,"compact-revision":26468} {"level":"info","ts":"2026-05-09T06:18:21.850723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31224} {"level":"info","ts":"2026-05-09T06:18:21.974154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31224,"took":"119.756075ms","hash":611999191,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":31895552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T06:18:21.974228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":611999191,"revision":31224,"compact-revision":29007} {"level":"info","ts":"2026-05-09T06:23:21.858733Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33557} {"level":"info","ts":"2026-05-09T06:23:22.003068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33557,"took":"139.523004ms","hash":2767463415,"current-db-size-bytes":58843136,"current-db-size":"59 MB","current-db-size-in-use-bytes":32235520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T06:23:22.003128Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2767463415,"revision":33557,"compact-revision":31224} {"level":"info","ts":"2026-05-09T06:28:21.866991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35782} {"level":"info","ts":"2026-05-09T06:28:22.003178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35782,"took":"131.567358ms","hash":1133093131,"current-db-size-bytes":59731968,"current-db-size":"60 MB","current-db-size-in-use-bytes":31088640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-09T06:28:22.003246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1133093131,"revision":35782,"compact-revision":33557}