{"level":"warn","ts":"2026-06-04T11:59:03.114985Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:03.115079Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-06-04T11:59:03.11515Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-04T11:59:03.115162Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-04T11:59:03.115179Z","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-06-04T11:59:03.115806Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-06-04T11:59:03.115884Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-06-04T11:59:03.128528Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.406164ms"} {"level":"info","ts":"2026-06-04T11:59:03.151321Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-06-04T11:59:03.151409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-04T11:59:03.151432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-06-04T11:59:03.15145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-04T11:59:03.15146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-06-04T11:59:03.151501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-06-04T11:59:03.179281Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-04T11:59:03.189801Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-04T11:59:03.198734Z","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-06-04T11:59:03.211131Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-04T11:59:03.211369Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-04T11:59:03.211517Z","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-06-04T11:59:03.211707Z","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-06-04T11:59:03.21173Z","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-06-04T11:59:03.213293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-06-04T11:59:03.213899Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-06-04T11:59:03.216764Z","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-06-04T11:59:03.216923Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-04T11:59:03.217005Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-06-04T11:59:03.21715Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-04T11:59:03.217263Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-04T11:59:04.052524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-04T11:59:04.052584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-04T11:59:04.052622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-06-04T11:59:04.052646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-06-04T11:59:04.052662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-04T11:59:04.052683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-06-04T11:59:04.052702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-06-04T11:59:04.053668Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:04.054045Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-06-04T11:59:04.054053Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:04.054071Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-04T11:59:04.054505Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-04T11:59:04.054545Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-04T11:59:04.054676Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:04.054798Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:04.054833Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-04T11:59:04.057751Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-06-04T11:59:04.058239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-04T11:59:48.730726Z","caller":"traceutil/trace.go:171","msg":"trace[505501635] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"182.145164ms","start":"2026-06-04T11:59:48.548532Z","end":"2026-06-04T11:59:48.730677Z","steps":["trace[505501635] 'process raft request' (duration: 181.259222ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T11:59:49.226758Z","caller":"traceutil/trace.go:171","msg":"trace[1731066909] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"197.837055ms","start":"2026-06-04T11:59:49.02886Z","end":"2026-06-04T11:59:49.226697Z","steps":["trace[1731066909] 'read index received' (duration: 193.018508ms)","trace[1731066909] 'applied index is now lower than readState.Index' (duration: 4.816247ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T11:59:49.226773Z","caller":"traceutil/trace.go:171","msg":"trace[848126466] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"431.56181ms","start":"2026-06-04T11:59:48.79515Z","end":"2026-06-04T11:59:49.226712Z","steps":["trace[848126466] 'process raft request' (duration: 426.726623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:59:49.226954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.084221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tqqpr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-04T11:59:49.227034Z","caller":"traceutil/trace.go:171","msg":"trace[1491082520] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tqqpr; range_end:; response_count:1; response_revision:593; }","duration":"198.210584ms","start":"2026-06-04T11:59:49.028805Z","end":"2026-06-04T11:59:49.227016Z","steps":["trace[1491082520] 'agreement among raft nodes before linearized reading' (duration: 197.976738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T11:59:49.227545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T11:59:48.795122Z","time spent":"431.769286ms","remote":"127.0.0.1:50752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-04T12:00:07.242433Z","caller":"traceutil/trace.go:171","msg":"trace[210888486] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"107.866669ms","start":"2026-06-04T12:00:07.134529Z","end":"2026-06-04T12:00:07.242395Z","steps":["trace[210888486] 'read index received' (duration: 107.679575ms)","trace[210888486] 'applied index is now lower than readState.Index' (duration: 184.784µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:00:07.242784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.215168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xn97m\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-04T12:00:07.242917Z","caller":"traceutil/trace.go:171","msg":"trace[2015897987] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xn97m; range_end:; response_count:1; response_revision:763; }","duration":"108.418593ms","start":"2026-06-04T12:00:07.134468Z","end":"2026-06-04T12:00:07.242887Z","steps":["trace[2015897987] 'agreement among raft nodes before linearized reading' (duration: 108.048194ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:27.494839Z","caller":"traceutil/trace.go:171","msg":"trace[17616551] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"131.846326ms","start":"2026-06-04T12:01:27.362974Z","end":"2026-06-04T12:01:27.49482Z","steps":["trace[17616551] 'read index received' (duration: 131.706872ms)","trace[17616551] 'applied index is now lower than readState.Index' (duration: 138.334µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:01:27.495026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.01962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:01:27.495125Z","caller":"traceutil/trace.go:171","msg":"trace[271479913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1847; }","duration":"132.182415ms","start":"2026-06-04T12:01:27.362929Z","end":"2026-06-04T12:01:27.495111Z","steps":["trace[271479913] 'agreement among raft nodes before linearized reading' (duration: 132.00749ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:27.495406Z","caller":"traceutil/trace.go:171","msg":"trace[1527067223] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"191.183161ms","start":"2026-06-04T12:01:27.303697Z","end":"2026-06-04T12:01:27.49488Z","steps":["trace[1527067223] 'process raft request' (duration: 191.010257ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:01:44.299079Z","caller":"traceutil/trace.go:171","msg":"trace[1658282982] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"119.343079ms","start":"2026-06-04T12:01:44.179706Z","end":"2026-06-04T12:01:44.299049Z","steps":["trace[1658282982] 'process raft request' (duration: 119.095913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:17.342955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.460798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:02:17.343242Z","caller":"traceutil/trace.go:171","msg":"trace[1144624896] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"209.986053ms","start":"2026-06-04T12:02:17.133189Z","end":"2026-06-04T12:02:17.343175Z","steps":["trace[1144624896] 'process raft request' (duration: 72.335327ms)","trace[1144624896] 'compare' (duration: 136.15679ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:24.113027Z","caller":"traceutil/trace.go:171","msg":"trace[2065788549] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"352.528375ms","start":"2026-06-04T12:02:23.760475Z","end":"2026-06-04T12:02:24.113004Z","steps":["trace[2065788549] 'process raft request' (duration: 352.364171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.11316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:23.760445Z","time spent":"352.643478ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:02:24.621741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.46087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:02:24.621844Z","caller":"traceutil/trace.go:171","msg":"trace[576597759] linearizableReadLoop","detail":"{readStateIndex:2288; appliedIndex:2286; }","duration":"614.812805ms","start":"2026-06-04T12:02:24.007014Z","end":"2026-06-04T12:02:24.621827Z","steps":["trace[576597759] 'read index received' (duration: 105.887203ms)","trace[576597759] 'applied index is now lower than readState.Index' (duration: 508.923861ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:24.622007Z","caller":"traceutil/trace.go:171","msg":"trace[1077953477] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"798.481634ms","start":"2026-06-04T12:02:23.82351Z","end":"2026-06-04T12:02:24.621992Z","steps":["trace[1077953477] 'process raft request' (duration: 598.679367ms)","trace[1077953477] 'compare' (duration: 199.07801ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:02:24.622078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:23.823366Z","time spent":"798.67488ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:02:24.622316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.503682ms","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-06-04T12:02:24.622298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.260015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-06-04T12:02:24.622307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.463047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b5df16363d71d3\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-04T12:02:24.622357Z","caller":"traceutil/trace.go:171","msg":"trace[1034317398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2200; }","duration":"142.585454ms","start":"2026-06-04T12:02:24.479762Z","end":"2026-06-04T12:02:24.622347Z","steps":["trace[1034317398] 'agreement among raft nodes before linearized reading' (duration: 142.42955ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:24.622389Z","caller":"traceutil/trace.go:171","msg":"trace[133331260] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2200; }","duration":"615.397298ms","start":"2026-06-04T12:02:24.006962Z","end":"2026-06-04T12:02:24.62236Z","steps":["trace[133331260] 'agreement among raft nodes before linearized reading' (duration: 615.181333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.622457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:24.006475Z","time spent":"615.969432ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-04T12:02:24.622395Z","caller":"traceutil/trace.go:171","msg":"trace[533585860] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b5df16363d71d3; range_end:; response_count:1; response_revision:2200; }","duration":"430.59682ms","start":"2026-06-04T12:02:24.191771Z","end":"2026-06-04T12:02:24.622368Z","steps":["trace[533585860] 'agreement among raft nodes before linearized reading' (duration: 430.382355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.62254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:02:24.191749Z","time spent":"430.777895ms","remote":"127.0.0.1:50340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b5df16363d71d3\" "} {"level":"info","ts":"2026-06-04T12:02:24.805235Z","caller":"traceutil/trace.go:171","msg":"trace[59809838] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"178.810993ms","start":"2026-06-04T12:02:24.626399Z","end":"2026-06-04T12:02:24.80521Z","steps":["trace[59809838] 'process raft request' (duration: 173.256339ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:02:24.805539Z","caller":"traceutil/trace.go:171","msg":"trace[329046306] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2288; }","duration":"171.14641ms","start":"2026-06-04T12:02:24.63437Z","end":"2026-06-04T12:02:24.805517Z","steps":["trace[329046306] 'read index received' (duration: 44.98838ms)","trace[329046306] 'applied index is now lower than readState.Index' (duration: 126.15673ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:02:24.805712Z","caller":"traceutil/trace.go:171","msg":"trace[1588553995] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"177.38702ms","start":"2026-06-04T12:02:24.628314Z","end":"2026-06-04T12:02:24.805701Z","steps":["trace[1588553995] 'process raft request' (duration: 176.856147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:02:24.806006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.613832ms","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-06-04T12:02:24.806082Z","caller":"traceutil/trace.go:171","msg":"trace[834412066] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2204; }","duration":"171.679614ms","start":"2026-06-04T12:02:24.634362Z","end":"2026-06-04T12:02:24.806042Z","steps":["trace[834412066] 'agreement among raft nodes before linearized reading' (duration: 171.335555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:09.605286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.690731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:03:09.605471Z","caller":"traceutil/trace.go:171","msg":"trace[1330805276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2619; }","duration":"244.947108ms","start":"2026-06-04T12:03:09.360485Z","end":"2026-06-04T12:03:09.605432Z","steps":["trace[1330805276] 'range keys from in-memory index tree' (duration: 244.596059ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:03:45.033285Z","caller":"traceutil/trace.go:171","msg":"trace[1880471637] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"113.792162ms","start":"2026-06-04T12:03:44.919462Z","end":"2026-06-04T12:03:45.033254Z","steps":["trace[1880471637] 'process raft request' (duration: 113.633788ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:03:45.476069Z","caller":"traceutil/trace.go:171","msg":"trace[1166368781] linearizableReadLoop","detail":"{readStateIndex:2946; appliedIndex:2945; }","duration":"241.354844ms","start":"2026-06-04T12:03:45.234691Z","end":"2026-06-04T12:03:45.476046Z","steps":["trace[1166368781] 'read index received' (duration: 239.716806ms)","trace[1166368781] 'applied index is now lower than readState.Index' (duration: 1.636538ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:03:45.476249Z","caller":"traceutil/trace.go:171","msg":"trace[1511988200] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"341.497368ms","start":"2026-06-04T12:03:45.134743Z","end":"2026-06-04T12:03:45.476241Z","steps":["trace[1511988200] 'process raft request' (duration: 339.649926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.476328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:45.134721Z","time spent":"341.54674ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:03:45.476565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.871305ms","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-06-04T12:03:45.476593Z","caller":"traceutil/trace.go:171","msg":"trace[2045211169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2834; }","duration":"241.933957ms","start":"2026-06-04T12:03:45.234651Z","end":"2026-06-04T12:03:45.476585Z","steps":["trace[2045211169] 'agreement among raft nodes before linearized reading' (duration: 241.821234ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:03:45.599172Z","caller":"traceutil/trace.go:171","msg":"trace[2090874887] linearizableReadLoop","detail":"{readStateIndex:2947; appliedIndex:2946; }","duration":"123.022396ms","start":"2026-06-04T12:03:45.476125Z","end":"2026-06-04T12:03:45.599148Z","steps":["trace[2090874887] 'read index received' (duration: 99.452679ms)","trace[2090874887] 'applied index is now lower than readState.Index' (duration: 23.568477ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:03:45.599337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.549694ms","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-06-04T12:03:45.599382Z","caller":"traceutil/trace.go:171","msg":"trace[1567705246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2835; }","duration":"364.641346ms","start":"2026-06-04T12:03:45.234728Z","end":"2026-06-04T12:03:45.599369Z","steps":["trace[1567705246] 'agreement among raft nodes before linearized reading' (duration: 364.501743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.5994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.335284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:03:45.599459Z","caller":"traceutil/trace.go:171","msg":"trace[692157131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2835; }","duration":"235.448597ms","start":"2026-06-04T12:03:45.363998Z","end":"2026-06-04T12:03:45.599447Z","steps":["trace[692157131] 'agreement among raft nodes before linearized reading' (duration: 235.16238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.599422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:45.234689Z","time spent":"364.724598ms","remote":"127.0.0.1:50522","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-06-04T12:03:45.59949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.690441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-04T12:03:45.599585Z","caller":"traceutil/trace.go:171","msg":"trace[2030509534] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2836; }","duration":"359.816733ms","start":"2026-06-04T12:03:45.239746Z","end":"2026-06-04T12:03:45.599563Z","steps":["trace[2030509534] 'agreement among raft nodes before linearized reading' (duration: 359.474636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.599637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:45.239738Z","time spent":"359.890455ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-04T12:03:45.599313Z","caller":"traceutil/trace.go:171","msg":"trace[1215673204] transaction","detail":"{read_only:false; response_revision:2836; number_of_response:1; }","duration":"108.443178ms","start":"2026-06-04T12:03:45.490845Z","end":"2026-06-04T12:03:45.599289Z","steps":["trace[1215673204] 'process raft request' (duration: 108.273314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.599684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.648223ms","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-06-04T12:03:45.599748Z","caller":"traceutil/trace.go:171","msg":"trace[561274406] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2835; }","duration":"360.732914ms","start":"2026-06-04T12:03:45.238999Z","end":"2026-06-04T12:03:45.599732Z","steps":["trace[561274406] 'agreement among raft nodes before linearized reading' (duration: 360.212012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:03:45.599802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:03:45.238988Z","time spent":"360.800696ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:03:45.599613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.983209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:03:45.601674Z","caller":"traceutil/trace.go:171","msg":"trace[1973704261] transaction","detail":"{read_only:false; response_revision:2835; number_of_response:1; }","duration":"267.644644ms","start":"2026-06-04T12:03:45.331499Z","end":"2026-06-04T12:03:45.599144Z","steps":["trace[1973704261] 'process raft request' (duration: 244.067047ms)","trace[1973704261] 'compare' (duration: 23.423883ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:03:45.601712Z","caller":"traceutil/trace.go:171","msg":"trace[433623338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2835; }","duration":"171.43906ms","start":"2026-06-04T12:03:45.428503Z","end":"2026-06-04T12:03:45.599942Z","steps":["trace[433623338] 'agreement among raft nodes before linearized reading' (duration: 170.696583ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:06:15.702618Z","caller":"traceutil/trace.go:171","msg":"trace[200846851] transaction","detail":"{read_only:false; response_revision:3828; number_of_response:1; }","duration":"131.161048ms","start":"2026-06-04T12:06:15.571425Z","end":"2026-06-04T12:06:15.702586Z","steps":["trace[200846851] 'process raft request' (duration: 130.954463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:07:42.174043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.174011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-faff8bb8-5ba3-4f4a-a136-1d56fb71e65b\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-06-04T12:07:42.17419Z","caller":"traceutil/trace.go:171","msg":"trace[74356003] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-faff8bb8-5ba3-4f4a-a136-1d56fb71e65b; range_end:; response_count:1; response_revision:5025; }","duration":"200.408445ms","start":"2026-06-04T12:07:41.973752Z","end":"2026-06-04T12:07:42.17416Z","steps":["trace[74356003] 'range keys from in-memory index tree' (duration: 199.914715ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:07:42.190332Z","caller":"traceutil/trace.go:171","msg":"trace[281018257] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"170.652997ms","start":"2026-06-04T12:07:42.019642Z","end":"2026-06-04T12:07:42.190295Z","steps":["trace[281018257] 'process raft request' (duration: 170.36761ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:03.133442Z","caller":"traceutil/trace.go:171","msg":"trace[1198354430] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"190.781536ms","start":"2026-06-04T12:08:02.942619Z","end":"2026-06-04T12:08:03.1334Z","steps":["trace[1198354430] 'process raft request' (duration: 190.475427ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:03.133621Z","caller":"traceutil/trace.go:171","msg":"trace[1075099320] transaction","detail":"{read_only:false; response_revision:5449; number_of_response:1; }","duration":"190.693833ms","start":"2026-06-04T12:08:02.942886Z","end":"2026-06-04T12:08:03.13358Z","steps":["trace[1075099320] 'process raft request' (duration: 190.393325ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:03.13375Z","caller":"traceutil/trace.go:171","msg":"trace[1339419325] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"190.686872ms","start":"2026-06-04T12:08:02.942998Z","end":"2026-06-04T12:08:03.133685Z","steps":["trace[1339419325] 'process raft request' (duration: 190.447257ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:03.133936Z","caller":"traceutil/trace.go:171","msg":"trace[1326467893] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"187.575098ms","start":"2026-06-04T12:08:02.946309Z","end":"2026-06-04T12:08:03.133884Z","steps":["trace[1326467893] 'process raft request' (duration: 187.207909ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:08:03.133861Z","caller":"traceutil/trace.go:171","msg":"trace[424259876] linearizableReadLoop","detail":"{readStateIndex:5658; appliedIndex:5653; }","duration":"169.870494ms","start":"2026-06-04T12:08:02.963967Z","end":"2026-06-04T12:08:03.133837Z","steps":["trace[424259876] 'read index received' (duration: 52.231673ms)","trace[424259876] 'applied index is now lower than readState.Index' (duration: 117.637061ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:08:03.135496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.508082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-06-04T12:08:03.135703Z","caller":"traceutil/trace.go:171","msg":"trace[1078460659] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5451; }","duration":"171.759289ms","start":"2026-06-04T12:08:02.963925Z","end":"2026-06-04T12:08:03.135684Z","steps":["trace[1078460659] 'agreement among raft nodes before linearized reading' (duration: 169.979887ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:09:04.081791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-06-04T12:09:04.163067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"79.147785ms","hash":2670492510,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":33947648,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-04T12:09:04.163141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2670492510,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-06-04T12:14:04.096328Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6020} {"level":"info","ts":"2026-06-04T12:14:04.190727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6020,"took":"92.528296ms","hash":298006823,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":21790720,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-04T12:14:04.190833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":298006823,"revision":6020,"compact-revision":2939} {"level":"info","ts":"2026-06-04T12:14:18.001178Z","caller":"traceutil/trace.go:171","msg":"trace[729098307] transaction","detail":"{read_only:false; response_revision:8318; number_of_response:1; }","duration":"213.407458ms","start":"2026-06-04T12:14:17.787744Z","end":"2026-06-04T12:14:18.001151Z","steps":["trace[729098307] 'process raft request' (duration: 212.187209ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:29.733741Z","caller":"traceutil/trace.go:171","msg":"trace[210931701] transaction","detail":"{read_only:false; response_revision:8381; number_of_response:1; }","duration":"199.21255ms","start":"2026-06-04T12:14:29.534497Z","end":"2026-06-04T12:14:29.733709Z","steps":["trace[210931701] 'process raft request' (duration: 158.793267ms)","trace[210931701] 'compare' (duration: 39.922611ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:31.452141Z","caller":"traceutil/trace.go:171","msg":"trace[623509821] linearizableReadLoop","detail":"{readStateIndex:8689; appliedIndex:8688; }","duration":"111.569791ms","start":"2026-06-04T12:14:31.340537Z","end":"2026-06-04T12:14:31.452106Z","steps":["trace[623509821] 'read index received' (duration: 50.816592ms)","trace[623509821] 'applied index is now lower than readState.Index' (duration: 60.751348ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:14:31.452367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.837206ms","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-06-04T12:14:31.452442Z","caller":"traceutil/trace.go:171","msg":"trace[1492703012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8387; }","duration":"111.962249ms","start":"2026-06-04T12:14:31.340464Z","end":"2026-06-04T12:14:31.452427Z","steps":["trace[1492703012] 'agreement among raft nodes before linearized reading' (duration: 111.756994ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:31.452742Z","caller":"traceutil/trace.go:171","msg":"trace[1969055165] transaction","detail":"{read_only:false; response_revision:8387; number_of_response:1; }","duration":"133.013202ms","start":"2026-06-04T12:14:31.319156Z","end":"2026-06-04T12:14:31.452169Z","steps":["trace[1969055165] 'process raft request' (duration: 72.227303ms)","trace[1969055165] 'compare' (duration: 60.528773ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:31.601977Z","caller":"traceutil/trace.go:171","msg":"trace[1917843664] transaction","detail":"{read_only:false; response_revision:8389; number_of_response:1; }","duration":"103.472717ms","start":"2026-06-04T12:14:31.498469Z","end":"2026-06-04T12:14:31.601942Z","steps":["trace[1917843664] 'process raft request' (duration: 87.275821ms)","trace[1917843664] 'compare' (duration: 16.008942ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:14:59.117163Z","caller":"traceutil/trace.go:171","msg":"trace[656757680] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"151.163983ms","start":"2026-06-04T12:14:58.965956Z","end":"2026-06-04T12:14:59.11712Z","steps":["trace[656757680] 'process raft request' (duration: 150.915537ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:14:59.1339Z","caller":"traceutil/trace.go:171","msg":"trace[1411447942] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"161.669741ms","start":"2026-06-04T12:14:58.972176Z","end":"2026-06-04T12:14:59.133845Z","steps":["trace[1411447942] 'process raft request' (duration: 161.462756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:12.724367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.209997ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-04T12:15:12.724708Z","caller":"traceutil/trace.go:171","msg":"trace[1181360928] linearizableReadLoop","detail":"{readStateIndex:9018; appliedIndex:9017; }","duration":"168.143023ms","start":"2026-06-04T12:15:12.556516Z","end":"2026-06-04T12:15:12.724659Z","steps":["trace[1181360928] 'read index received' (duration: 4.560817ms)","trace[1181360928] 'applied index is now lower than readState.Index' (duration: 163.577066ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:15:12.726515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.965756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-04T12:15:12.726937Z","caller":"traceutil/trace.go:171","msg":"trace[933036026] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8702; }","duration":"170.365324ms","start":"2026-06-04T12:15:12.556252Z","end":"2026-06-04T12:15:12.726617Z","steps":["trace[933036026] 'agreement among raft nodes before linearized reading' (duration: 168.580953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:13.701131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.265352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:15:13.701273Z","caller":"traceutil/trace.go:171","msg":"trace[466425749] linearizableReadLoop","detail":"{readStateIndex:9024; appliedIndex:9023; }","duration":"154.321487ms","start":"2026-06-04T12:15:13.546931Z","end":"2026-06-04T12:15:13.701253Z","steps":["trace[466425749] 'read index received' (duration: 578.944µs)","trace[466425749] 'applied index is now lower than readState.Index' (duration: 153.740643ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:15:13.701371Z","caller":"traceutil/trace.go:171","msg":"trace[1337816157] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"321.277821ms","start":"2026-06-04T12:15:13.380037Z","end":"2026-06-04T12:15:13.701315Z","steps":["trace[1337816157] 'process raft request' (duration: 43.695382ms)","trace[1337816157] 'compare' (duration: 276.911034ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:15:13.701544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.586902ms","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-06-04T12:15:13.701708Z","caller":"traceutil/trace.go:171","msg":"trace[1541189435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8707; }","duration":"154.817129ms","start":"2026-06-04T12:15:13.546856Z","end":"2026-06-04T12:15:13.701673Z","steps":["trace[1541189435] 'agreement among raft nodes before linearized reading' (duration: 154.45145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:15:13.701604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:15:13.380017Z","time spent":"321.463265ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:15:28.564959Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d4.wal"} {"level":"info","ts":"2026-06-04T12:16:30.697781Z","caller":"traceutil/trace.go:171","msg":"trace[604992962] transaction","detail":"{read_only:false; response_revision:9463; number_of_response:1; }","duration":"104.272524ms","start":"2026-06-04T12:16:30.593475Z","end":"2026-06-04T12:16:30.697747Z","steps":["trace[604992962] 'process raft request' (duration: 104.062579ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:16:30.847979Z","caller":"traceutil/trace.go:171","msg":"trace[418502740] transaction","detail":"{read_only:false; response_revision:9464; number_of_response:1; }","duration":"110.49141ms","start":"2026-06-04T12:16:30.737447Z","end":"2026-06-04T12:16:30.847939Z","steps":["trace[418502740] 'process raft request' (duration: 110.317016ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:16:56.946179Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:16:56.952279Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-04T12:16:56.952438Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-04T12:19:04.103968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8104} {"level":"info","ts":"2026-06-04T12:19:04.17044Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8104,"took":"64.687523ms","hash":2673246477,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":29208576,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-04T12:19:04.170524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2673246477,"revision":8104,"compact-revision":6020} {"level":"info","ts":"2026-06-04T12:20:12.850747Z","caller":"traceutil/trace.go:171","msg":"trace[1172182033] transaction","detail":"{read_only:false; response_revision:11298; number_of_response:1; }","duration":"203.684519ms","start":"2026-06-04T12:20:12.64704Z","end":"2026-06-04T12:20:12.850725Z","steps":["trace[1172182033] 'process raft request' (duration: 203.508325ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:24:04.111548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10626} {"level":"info","ts":"2026-06-04T12:24:04.198156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10626,"took":"83.734169ms","hash":2217387801,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":26918912,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-04T12:24:04.198336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2217387801,"revision":10626,"compact-revision":8104} {"level":"info","ts":"2026-06-04T12:26:07.625251Z","caller":"traceutil/trace.go:171","msg":"trace[1195771463] transaction","detail":"{read_only:false; response_revision:13717; number_of_response:1; }","duration":"126.523308ms","start":"2026-06-04T12:26:07.498704Z","end":"2026-06-04T12:26:07.625227Z","steps":["trace[1195771463] 'process raft request' (duration: 126.375774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:26:49.449285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.847531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-04T12:26:49.449406Z","caller":"traceutil/trace.go:171","msg":"trace[147405306] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:13950; }","duration":"164.058186ms","start":"2026-06-04T12:26:49.285322Z","end":"2026-06-04T12:26:49.44938Z","steps":["trace[147405306] 'count revisions from in-memory index tree' (duration: 163.696737ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:26:49.634701Z","caller":"traceutil/trace.go:171","msg":"trace[398927386] transaction","detail":"{read_only:false; response_revision:13951; number_of_response:1; }","duration":"179.126949ms","start":"2026-06-04T12:26:49.455545Z","end":"2026-06-04T12:26:49.634672Z","steps":["trace[398927386] 'process raft request' (duration: 178.895353ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:26:49.672048Z","caller":"traceutil/trace.go:171","msg":"trace[528251999] transaction","detail":"{read_only:false; response_revision:13952; number_of_response:1; }","duration":"201.325778ms","start":"2026-06-04T12:26:49.470672Z","end":"2026-06-04T12:26:49.671998Z","steps":["trace[528251999] 'process raft request' (duration: 201.050641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:27:11.48287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.78267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-04T12:27:11.483001Z","caller":"traceutil/trace.go:171","msg":"trace[28523663] transaction","detail":"{read_only:false; response_revision:14255; number_of_response:1; }","duration":"256.671365ms","start":"2026-06-04T12:27:11.226308Z","end":"2026-06-04T12:27:11.48298Z","steps":["trace[28523663] 'process raft request' (duration: 96.67567ms)","trace[28523663] 'compare' (duration: 159.593226ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:27:11.679319Z","caller":"traceutil/trace.go:171","msg":"trace[213733841] linearizableReadLoop","detail":"{readStateIndex:14755; appliedIndex:14754; }","duration":"317.428147ms","start":"2026-06-04T12:27:11.361845Z","end":"2026-06-04T12:27:11.679273Z","steps":["trace[213733841] 'read index received' (duration: 266.858827ms)","trace[213733841] 'applied index is now lower than readState.Index' (duration: 50.56722ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:27:11.67959Z","caller":"traceutil/trace.go:171","msg":"trace[1102084162] transaction","detail":"{read_only:false; response_revision:14256; number_of_response:1; }","duration":"373.108384ms","start":"2026-06-04T12:27:11.306442Z","end":"2026-06-04T12:27:11.67955Z","steps":["trace[1102084162] 'process raft request' (duration: 322.248797ms)","trace[1102084162] 'compare' (duration: 50.364305ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:27:11.679808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:27:11.306408Z","time spent":"373.278848ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:27:11.68005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.006082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:27:11.68024Z","caller":"traceutil/trace.go:171","msg":"trace[1767985615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14256; }","duration":"318.450762ms","start":"2026-06-04T12:27:11.361768Z","end":"2026-06-04T12:27:11.680218Z","steps":["trace[1767985615] 'agreement among raft nodes before linearized reading' (duration: 317.666073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:27:11.68116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:27:11.361738Z","time spent":"318.683498ms","remote":"127.0.0.1:50246","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-06-04T12:28:38.117829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.707301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-04T12:28:38.117943Z","caller":"traceutil/trace.go:171","msg":"trace[1954134822] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15066; }","duration":"143.899626ms","start":"2026-06-04T12:28:37.97402Z","end":"2026-06-04T12:28:38.11792Z","steps":["trace[1954134822] 'range keys from in-memory index tree' (duration: 143.486816ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.117938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.699915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-nf4hr\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-06-04T12:28:38.118317Z","caller":"traceutil/trace.go:171","msg":"trace[1074468584] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-nf4hr; range_end:; response_count:1; response_revision:15066; }","duration":"128.129745ms","start":"2026-06-04T12:28:37.990154Z","end":"2026-06-04T12:28:38.118284Z","steps":["trace[1074468584] 'range keys from in-memory index tree' (duration: 127.370417ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.202858Z","caller":"traceutil/trace.go:171","msg":"trace[662474311] linearizableReadLoop","detail":"{readStateIndex:15589; appliedIndex:15588; }","duration":"171.043947ms","start":"2026-06-04T12:28:38.031785Z","end":"2026-06-04T12:28:38.202829Z","steps":["trace[662474311] 'read index received' (duration: 170.73655ms)","trace[662474311] 'applied index is now lower than readState.Index' (duration: 306.177µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:28:38.203242Z","caller":"traceutil/trace.go:171","msg":"trace[1460934672] transaction","detail":"{read_only:false; response_revision:15067; number_of_response:1; }","duration":"184.055238ms","start":"2026-06-04T12:28:38.019166Z","end":"2026-06-04T12:28:38.203222Z","steps":["trace[1460934672] 'process raft request' (duration: 183.437274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.203738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.941038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-04T12:28:38.203816Z","caller":"traceutil/trace.go:171","msg":"trace[1374337793] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15067; }","duration":"172.082141ms","start":"2026-06-04T12:28:38.031716Z","end":"2026-06-04T12:28:38.203798Z","steps":["trace[1374337793] 'agreement among raft nodes before linearized reading' (duration: 171.840475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.584103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.819997ms","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-06-04T12:28:38.584265Z","caller":"traceutil/trace.go:171","msg":"trace[1758022011] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15068; }","duration":"117.012891ms","start":"2026-06-04T12:28:38.46723Z","end":"2026-06-04T12:28:38.584243Z","steps":["trace[1758022011] 'range keys from in-memory index tree' (duration: 116.796556ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.584815Z","caller":"traceutil/trace.go:171","msg":"trace[72531020] transaction","detail":"{read_only:false; response_revision:15070; number_of_response:1; }","duration":"102.453131ms","start":"2026-06-04T12:28:38.482328Z","end":"2026-06-04T12:28:38.584781Z","steps":["trace[72531020] 'process raft request' (duration: 102.384349ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.584841Z","caller":"traceutil/trace.go:171","msg":"trace[183095887] linearizableReadLoop","detail":"{readStateIndex:15591; appliedIndex:15589; }","duration":"231.443319ms","start":"2026-06-04T12:28:38.353368Z","end":"2026-06-04T12:28:38.584811Z","steps":["trace[183095887] 'read index received' (duration: 32.682774ms)","trace[183095887] 'applied index is now lower than readState.Index' (duration: 198.757895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:28:38.585162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.770837ms","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-06-04T12:28:38.585217Z","caller":"traceutil/trace.go:171","msg":"trace[453115474] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15070; }","duration":"231.909571ms","start":"2026-06-04T12:28:38.353297Z","end":"2026-06-04T12:28:38.585206Z","steps":["trace[453115474] 'agreement among raft nodes before linearized reading' (duration: 231.791217ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.584841Z","caller":"traceutil/trace.go:171","msg":"trace[64651937] transaction","detail":"{read_only:false; response_revision:15069; number_of_response:1; }","duration":"257.597888ms","start":"2026-06-04T12:28:38.3272Z","end":"2026-06-04T12:28:38.584798Z","steps":["trace[64651937] 'process raft request' (duration: 203.10539ms)","trace[64651937] 'compare' (duration: 53.796082ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:28:38.585328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.508881ms","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-06-04T12:28:38.585362Z","caller":"traceutil/trace.go:171","msg":"trace[1728425656] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15070; }","duration":"226.594063ms","start":"2026-06-04T12:28:38.358759Z","end":"2026-06-04T12:28:38.585353Z","steps":["trace[1728425656] 'agreement among raft nodes before linearized reading' (duration: 226.506301ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.585433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.251459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:28:38.585495Z","caller":"traceutil/trace.go:171","msg":"trace[1184065673] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15070; }","duration":"140.32918ms","start":"2026-06-04T12:28:38.445151Z","end":"2026-06-04T12:28:38.58548Z","steps":["trace[1184065673] 'agreement among raft nodes before linearized reading' (duration: 140.173257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.585554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.922446ms","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-06-04T12:28:38.585586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.123687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:28:38.58559Z","caller":"traceutil/trace.go:171","msg":"trace[893577064] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15070; }","duration":"140.961977ms","start":"2026-06-04T12:28:38.444617Z","end":"2026-06-04T12:28:38.585579Z","steps":["trace[893577064] 'agreement among raft nodes before linearized reading' (duration: 140.917646ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.585609Z","caller":"traceutil/trace.go:171","msg":"trace[703409689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15070; }","duration":"222.147827ms","start":"2026-06-04T12:28:38.363454Z","end":"2026-06-04T12:28:38.585602Z","steps":["trace[703409689] 'agreement among raft nodes before linearized reading' (duration: 222.098056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.585739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.405468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:28:38.585761Z","caller":"traceutil/trace.go:171","msg":"trace[1171307886] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15070; }","duration":"226.44106ms","start":"2026-06-04T12:28:38.359313Z","end":"2026-06-04T12:28:38.585754Z","steps":["trace[1171307886] 'agreement among raft nodes before linearized reading' (duration: 226.401499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.585786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.296404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-06-04T12:28:38.585815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.404737ms","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-06-04T12:28:38.585843Z","caller":"traceutil/trace.go:171","msg":"trace[1288938574] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15070; }","duration":"141.444538ms","start":"2026-06-04T12:28:38.444389Z","end":"2026-06-04T12:28:38.585834Z","steps":["trace[1288938574] 'agreement among raft nodes before linearized reading' (duration: 141.394837ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.58584Z","caller":"traceutil/trace.go:171","msg":"trace[896973374] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15070; }","duration":"116.386717ms","start":"2026-06-04T12:28:38.469438Z","end":"2026-06-04T12:28:38.585825Z","steps":["trace[896973374] 'agreement among raft nodes before linearized reading' (duration: 116.064539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.58588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.019374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-04T12:28:38.585901Z","caller":"traceutil/trace.go:171","msg":"trace[347030815] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15070; }","duration":"227.072024ms","start":"2026-06-04T12:28:38.358823Z","end":"2026-06-04T12:28:38.585895Z","steps":["trace[347030815] 'agreement among raft nodes before linearized reading' (duration: 227.031703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.58599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.94822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-04T12:28:38.586008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.74489ms","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-06-04T12:28:38.586042Z","caller":"traceutil/trace.go:171","msg":"trace[1309860133] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15070; }","duration":"132.774861ms","start":"2026-06-04T12:28:38.453255Z","end":"2026-06-04T12:28:38.58603Z","steps":["trace[1309860133] 'agreement among raft nodes before linearized reading' (duration: 132.686908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.586084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.280868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-06-04T12:28:38.586094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.757544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-04T12:28:38.586107Z","caller":"traceutil/trace.go:171","msg":"trace[42629800] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15070; }","duration":"132.301748ms","start":"2026-06-04T12:28:38.453798Z","end":"2026-06-04T12:28:38.5861Z","steps":["trace[42629800] 'agreement among raft nodes before linearized reading' (duration: 132.165335ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.586014Z","caller":"traceutil/trace.go:171","msg":"trace[564305560] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15070; }","duration":"142.027752ms","start":"2026-06-04T12:28:38.443979Z","end":"2026-06-04T12:28:38.586007Z","steps":["trace[564305560] 'agreement among raft nodes before linearized reading' (duration: 141.980631ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:28:38.586116Z","caller":"traceutil/trace.go:171","msg":"trace[1883965981] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15070; }","duration":"138.805674ms","start":"2026-06-04T12:28:38.447304Z","end":"2026-06-04T12:28:38.58611Z","steps":["trace[1883965981] 'agreement among raft nodes before linearized reading' (duration: 138.735093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:28:38.585502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.638824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-04T12:28:38.586256Z","caller":"traceutil/trace.go:171","msg":"trace[1823409258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15070; }","duration":"217.438234ms","start":"2026-06-04T12:28:38.36881Z","end":"2026-06-04T12:28:38.586248Z","steps":["trace[1823409258] 'agreement among raft nodes before linearized reading' (duration: 216.513412ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:29:04.119154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12791} {"level":"info","ts":"2026-06-04T12:29:04.204055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12791,"took":"82.222356ms","hash":737804123,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":29622272,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-04T12:29:04.204131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":737804123,"revision":12791,"compact-revision":10626} {"level":"warn","ts":"2026-06-04T12:32:43.306671Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018982427s","expected-duration":"1s"} {"level":"info","ts":"2026-06-04T12:32:43.309387Z","caller":"traceutil/trace.go:171","msg":"trace[946284599] linearizableReadLoop","detail":"{readStateIndex:17528; appliedIndex:17527; }","duration":"298.491865ms","start":"2026-06-04T12:32:43.010859Z","end":"2026-06-04T12:32:43.309351Z","steps":["trace[946284599] 'read index received' (duration: 296.161109ms)","trace[946284599] 'applied index is now lower than readState.Index' (duration: 2.326356ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:32:43.309469Z","caller":"traceutil/trace.go:171","msg":"trace[878950668] transaction","detail":"{read_only:false; response_revision:16950; number_of_response:1; }","duration":"118.78939ms","start":"2026-06-04T12:32:43.190651Z","end":"2026-06-04T12:32:43.30944Z","steps":["trace[878950668] 'process raft request' (duration: 118.758969ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.309525Z","caller":"traceutil/trace.go:171","msg":"trace[1415704147] transaction","detail":"{read_only:false; response_revision:16948; number_of_response:1; }","duration":"297.726725ms","start":"2026-06-04T12:32:43.011762Z","end":"2026-06-04T12:32:43.309489Z","steps":["trace[1415704147] 'process raft request' (duration: 297.482109ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.309638Z","caller":"traceutil/trace.go:171","msg":"trace[1692625183] transaction","detail":"{read_only:false; response_revision:16949; number_of_response:1; }","duration":"134.060937ms","start":"2026-06-04T12:32:43.175523Z","end":"2026-06-04T12:32:43.309584Z","steps":["trace[1692625183] 'process raft request' (duration: 133.852223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.309925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.816394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:32:43.310188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.139272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-06-04T12:32:43.310234Z","caller":"traceutil/trace.go:171","msg":"trace[1479935415] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16950; }","duration":"297.186582ms","start":"2026-06-04T12:32:43.013033Z","end":"2026-06-04T12:32:43.31022Z","steps":["trace[1479935415] 'agreement among raft nodes before linearized reading' (duration: 297.026919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.310245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.715548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:32:43.310312Z","caller":"traceutil/trace.go:171","msg":"trace[217535482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16950; }","duration":"293.833872ms","start":"2026-06-04T12:32:43.016458Z","end":"2026-06-04T12:32:43.310292Z","steps":["trace[217535482] 'agreement among raft nodes before linearized reading' (duration: 293.656518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.310328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.431632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-06-04T12:32:43.310333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.403182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-06-04T12:32:43.310378Z","caller":"traceutil/trace.go:171","msg":"trace[2056767115] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16950; }","duration":"298.566095ms","start":"2026-06-04T12:32:43.011796Z","end":"2026-06-04T12:32:43.310362Z","steps":["trace[2056767115] 'agreement among raft nodes before linearized reading' (duration: 298.35288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.310421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.856952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-nf4hr\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-06-04T12:32:43.310434Z","caller":"traceutil/trace.go:171","msg":"trace[1648890859] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16950; }","duration":"298.504904ms","start":"2026-06-04T12:32:43.011898Z","end":"2026-06-04T12:32:43.310403Z","steps":["trace[1648890859] 'agreement among raft nodes before linearized reading' (duration: 298.210777ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.31055Z","caller":"traceutil/trace.go:171","msg":"trace[1976312931] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-nf4hr; range_end:; response_count:1; response_revision:16950; }","duration":"293.949444ms","start":"2026-06-04T12:32:43.016537Z","end":"2026-06-04T12:32:43.310486Z","steps":["trace[1976312931] 'agreement among raft nodes before linearized reading' (duration: 293.551364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.30998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.122061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:32:43.310751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.971756ms","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-06-04T12:32:43.310836Z","caller":"traceutil/trace.go:171","msg":"trace[1212543058] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:16950; }","duration":"137.968592ms","start":"2026-06-04T12:32:43.172785Z","end":"2026-06-04T12:32:43.310754Z","steps":["trace[1212543058] 'agreement among raft nodes before linearized reading' (duration: 137.138602ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.310931Z","caller":"traceutil/trace.go:171","msg":"trace[205009471] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:16950; }","duration":"299.245773ms","start":"2026-06-04T12:32:43.011657Z","end":"2026-06-04T12:32:43.310902Z","steps":["trace[205009471] 'agreement among raft nodes before linearized reading' (duration: 297.868539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.311523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.33062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:32:43.311616Z","caller":"traceutil/trace.go:171","msg":"trace[1631778082] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:16950; }","duration":"138.465914ms","start":"2026-06-04T12:32:43.173129Z","end":"2026-06-04T12:32:43.311595Z","steps":["trace[1631778082] 'agreement among raft nodes before linearized reading' (duration: 136.561018ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:32:43.31154Z","caller":"traceutil/trace.go:171","msg":"trace[1264853834] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:16950; }","duration":"288.485984ms","start":"2026-06-04T12:32:43.023026Z","end":"2026-06-04T12:32:43.311512Z","steps":["trace[1264853834] 'agreement among raft nodes before linearized reading' (duration: 286.609219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.310111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.113669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-06-04T12:32:43.31238Z","caller":"traceutil/trace.go:171","msg":"trace[1011253846] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16950; }","duration":"301.318733ms","start":"2026-06-04T12:32:43.010809Z","end":"2026-06-04T12:32:43.312127Z","steps":["trace[1011253846] 'agreement among raft nodes before linearized reading' (duration: 298.69265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:32:43.313163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:32:43.01079Z","time spent":"302.352967ms","remote":"127.0.0.1:50426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":723,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-06-04T12:33:52.091175Z","caller":"traceutil/trace.go:171","msg":"trace[1771654819] transaction","detail":"{read_only:false; response_revision:17391; number_of_response:1; }","duration":"119.208132ms","start":"2026-06-04T12:33:51.971943Z","end":"2026-06-04T12:33:52.091152Z","steps":["trace[1771654819] 'process raft request' (duration: 117.529181ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:04.127245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15200} {"level":"info","ts":"2026-06-04T12:34:04.225804Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15200,"took":"94.959859ms","hash":3032525065,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":31744000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T12:34:04.22591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3032525065,"revision":15200,"compact-revision":12791} {"level":"info","ts":"2026-06-04T12:34:42.567254Z","caller":"traceutil/trace.go:171","msg":"trace[1557951524] linearizableReadLoop","detail":"{readStateIndex:18345; appliedIndex:18344; }","duration":"146.147706ms","start":"2026-06-04T12:34:42.421069Z","end":"2026-06-04T12:34:42.567217Z","steps":["trace[1557951524] 'read index received' (duration: 145.856659ms)","trace[1557951524] 'applied index is now lower than readState.Index' (duration: 289.427µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:34:42.567485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.367592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:34:42.567582Z","caller":"traceutil/trace.go:171","msg":"trace[774029895] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:17737; }","duration":"146.569336ms","start":"2026-06-04T12:34:42.420998Z","end":"2026-06-04T12:34:42.567567Z","steps":["trace[774029895] 'agreement among raft nodes before linearized reading' (duration: 146.355161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:42.567592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.483834ms","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-06-04T12:34:42.567708Z","caller":"traceutil/trace.go:171","msg":"trace[640957643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17737; }","duration":"146.684179ms","start":"2026-06-04T12:34:42.420998Z","end":"2026-06-04T12:34:42.567682Z","steps":["trace[640957643] 'agreement among raft nodes before linearized reading' (duration: 146.324741ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:42.567302Z","caller":"traceutil/trace.go:171","msg":"trace[1293358450] transaction","detail":"{read_only:false; response_revision:17737; number_of_response:1; }","duration":"186.069896ms","start":"2026-06-04T12:34:42.381189Z","end":"2026-06-04T12:34:42.567259Z","steps":["trace[1293358450] 'process raft request' (duration: 185.80239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:42.897302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.337603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:34:42.89745Z","caller":"traceutil/trace.go:171","msg":"trace[1318349855] transaction","detail":"{read_only:false; response_revision:17740; number_of_response:1; }","duration":"246.160163ms","start":"2026-06-04T12:34:42.651256Z","end":"2026-06-04T12:34:42.897416Z","steps":["trace[1318349855] 'compare' (duration: 239.472053ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:42.897546Z","caller":"traceutil/trace.go:171","msg":"trace[692294477] linearizableReadLoop","detail":"{readStateIndex:18348; appliedIndex:18347; }","duration":"245.646851ms","start":"2026-06-04T12:34:42.651853Z","end":"2026-06-04T12:34:42.8975Z","steps":["trace[692294477] 'read index received' (duration: 4.935969ms)","trace[692294477] 'applied index is now lower than readState.Index' (duration: 240.703312ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:34:43.116133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.701571ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-04T12:34:43.116279Z","caller":"traceutil/trace.go:171","msg":"trace[2107938756] linearizableReadLoop","detail":"{readStateIndex:18349; appliedIndex:18348; }","duration":"218.597509ms","start":"2026-06-04T12:34:42.897654Z","end":"2026-06-04T12:34:43.116252Z","steps":["trace[2107938756] 'read index received' (duration: 62.441µs)","trace[2107938756] 'applied index is now lower than readState.Index' (duration: 218.533178ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:34:43.116599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.688681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:1574"} {"level":"warn","ts":"2026-06-04T12:34:43.116667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.368703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-04T12:34:43.116599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.892363ms","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-06-04T12:34:43.116774Z","caller":"traceutil/trace.go:171","msg":"trace[2113592408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17740; }","duration":"272.499856ms","start":"2026-06-04T12:34:42.844254Z","end":"2026-06-04T12:34:43.116754Z","steps":["trace[2113592408] 'agreement among raft nodes before linearized reading' (duration: 272.155238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:43.116767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.640452ms","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-06-04T12:34:43.116806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.412515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-06-04T12:34:43.116858Z","caller":"traceutil/trace.go:171","msg":"trace[1110152035] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17740; }","duration":"408.790146ms","start":"2026-06-04T12:34:42.708043Z","end":"2026-06-04T12:34:43.116834Z","steps":["trace[1110152035] 'agreement among raft nodes before linearized reading' (duration: 408.490049ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:43.116863Z","caller":"traceutil/trace.go:171","msg":"trace[330846328] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17740; }","duration":"117.511357ms","start":"2026-06-04T12:34:42.999332Z","end":"2026-06-04T12:34:43.116843Z","steps":["trace[330846328] 'agreement among raft nodes before linearized reading' (duration: 117.335093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:43.116868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.422995ms","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-06-04T12:34:43.116905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:34:42.707972Z","time spent":"408.925979ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-04T12:34:43.116874Z","caller":"traceutil/trace.go:171","msg":"trace[616280062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17740; }","duration":"415.170909ms","start":"2026-06-04T12:34:42.701649Z","end":"2026-06-04T12:34:43.116819Z","steps":["trace[616280062] 'agreement among raft nodes before linearized reading' (duration: 414.825351ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:43.116973Z","caller":"traceutil/trace.go:171","msg":"trace[1274554808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17740; }","duration":"191.570458ms","start":"2026-06-04T12:34:42.925371Z","end":"2026-06-04T12:34:43.116942Z","steps":["trace[1274554808] 'agreement among raft nodes before linearized reading' (duration: 191.21642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:43.117045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:34:42.701628Z","time spent":"415.364284ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-04T12:34:43.116735Z","caller":"traceutil/trace.go:171","msg":"trace[347453236] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17740; }","duration":"464.837334ms","start":"2026-06-04T12:34:42.651836Z","end":"2026-06-04T12:34:43.116673Z","steps":["trace[347453236] 'agreement among raft nodes before linearized reading' (duration: 245.795214ms)","trace[347453236] 'range keys from in-memory index tree' (duration: 218.741593ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:34:43.117326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:34:42.651825Z","time spent":"465.42814ms","remote":"127.0.0.1:36516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":1597,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" "} {"level":"info","ts":"2026-06-04T12:34:43.241865Z","caller":"traceutil/trace.go:171","msg":"trace[1065040043] transaction","detail":"{read_only:false; response_revision:17742; number_of_response:1; }","duration":"116.317448ms","start":"2026-06-04T12:34:43.12551Z","end":"2026-06-04T12:34:43.241828Z","steps":["trace[1065040043] 'process raft request' (duration: 95.066206ms)","trace[1065040043] 'compare' (duration: 21.040297ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:34:43.242347Z","caller":"traceutil/trace.go:171","msg":"trace[374915541] transaction","detail":"{read_only:false; response_revision:17743; number_of_response:1; }","duration":"116.763989ms","start":"2026-06-04T12:34:43.125542Z","end":"2026-06-04T12:34:43.242306Z","steps":["trace[374915541] 'process raft request' (duration: 116.229966ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:43.24287Z","caller":"traceutil/trace.go:171","msg":"trace[563801025] linearizableReadLoop","detail":"{readStateIndex:18353; appliedIndex:18349; }","duration":"115.004086ms","start":"2026-06-04T12:34:43.127833Z","end":"2026-06-04T12:34:43.242837Z","steps":["trace[563801025] 'read index received' (duration: 9.955269ms)","trace[563801025] 'applied index is now lower than readState.Index' (duration: 105.047487ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:34:43.242996Z","caller":"traceutil/trace.go:171","msg":"trace[817011464] transaction","detail":"{read_only:false; response_revision:17744; number_of_response:1; }","duration":"117.417194ms","start":"2026-06-04T12:34:43.125564Z","end":"2026-06-04T12:34:43.242981Z","steps":["trace[817011464] 'process raft request' (duration: 116.8493ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:34:43.243056Z","caller":"traceutil/trace.go:171","msg":"trace[1989650483] transaction","detail":"{read_only:false; response_revision:17745; number_of_response:1; }","duration":"113.730515ms","start":"2026-06-04T12:34:43.129313Z","end":"2026-06-04T12:34:43.243044Z","steps":["trace[1989650483] 'process raft request' (duration: 113.402498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:34:43.24369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.830068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5fc25876-7d12-4dfb-b6b3-73da2279ab95\" ","response":"range_response_count:1 size:1304"} {"level":"info","ts":"2026-06-04T12:34:43.243774Z","caller":"traceutil/trace.go:171","msg":"trace[1080292401] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5fc25876-7d12-4dfb-b6b3-73da2279ab95; range_end:; response_count:1; response_revision:17745; }","duration":"115.92898ms","start":"2026-06-04T12:34:43.127819Z","end":"2026-06-04T12:34:43.243748Z","steps":["trace[1080292401] 'agreement among raft nodes before linearized reading' (duration: 115.14035ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:37:58.444549Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:37:58.449879Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-04T12:37:58.450072Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-04T12:39:04.134042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17489} {"level":"info","ts":"2026-06-04T12:39:04.229479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17489,"took":"91.30942ms","hash":4106140944,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-04T12:39:04.229548Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4106140944,"revision":17489,"compact-revision":15200} {"level":"info","ts":"2026-06-04T12:40:31.519906Z","caller":"traceutil/trace.go:171","msg":"trace[1966536521] transaction","detail":"{read_only:false; response_revision:20433; number_of_response:1; }","duration":"132.138793ms","start":"2026-06-04T12:40:31.387732Z","end":"2026-06-04T12:40:31.519871Z","steps":["trace[1966536521] 'process raft request' (duration: 132.072061ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:40:31.520114Z","caller":"traceutil/trace.go:171","msg":"trace[1652147312] transaction","detail":"{read_only:false; response_revision:20432; number_of_response:1; }","duration":"217.797237ms","start":"2026-06-04T12:40:31.302261Z","end":"2026-06-04T12:40:31.520058Z","steps":["trace[1652147312] 'process raft request' (duration: 141.914409ms)","trace[1652147312] 'compare' (duration: 75.407307ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:40:31.520166Z","caller":"traceutil/trace.go:171","msg":"trace[2025878388] linearizableReadLoop","detail":"{readStateIndex:21130; appliedIndex:21128; }","duration":"206.069495ms","start":"2026-06-04T12:40:31.314063Z","end":"2026-06-04T12:40:31.520132Z","steps":["trace[2025878388] 'read index received' (duration: 43.714784ms)","trace[2025878388] 'applied index is now lower than readState.Index' (duration: 162.350441ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:40:31.520402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.903018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:40:31.520444Z","caller":"traceutil/trace.go:171","msg":"trace[1497821529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20433; }","duration":"158.97824ms","start":"2026-06-04T12:40:31.361455Z","end":"2026-06-04T12:40:31.520433Z","steps":["trace[1497821529] 'agreement among raft nodes before linearized reading' (duration: 158.858267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:40:31.520671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.528626ms","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-06-04T12:40:31.520787Z","caller":"traceutil/trace.go:171","msg":"trace[650086174] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20433; }","duration":"206.720891ms","start":"2026-06-04T12:40:31.314037Z","end":"2026-06-04T12:40:31.520758Z","steps":["trace[650086174] 'agreement among raft nodes before linearized reading' (duration: 206.223899ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:40:31.722211Z","caller":"traceutil/trace.go:171","msg":"trace[481704690] transaction","detail":"{read_only:false; response_revision:20434; number_of_response:1; }","duration":"196.000422ms","start":"2026-06-04T12:40:31.526151Z","end":"2026-06-04T12:40:31.722152Z","steps":["trace[481704690] 'process raft request' (duration: 139.935371ms)","trace[481704690] 'compare' (duration: 55.696342ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:40:31.981382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.000416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:40:31.981777Z","caller":"traceutil/trace.go:171","msg":"trace[1168411981] transaction","detail":"{read_only:false; response_revision:20435; number_of_response:1; }","duration":"215.156754ms","start":"2026-06-04T12:40:31.766458Z","end":"2026-06-04T12:40:31.981615Z","steps":["trace[1168411981] 'process raft request' (duration: 66.793279ms)","trace[1168411981] 'compare' (duration: 147.820272ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:44:04.141423Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19909} {"level":"info","ts":"2026-06-04T12:44:04.25178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19909,"took":"106.995376ms","hash":1292040352,"current-db-size-bytes":58531840,"current-db-size":"58 MB","current-db-size-in-use-bytes":30314496,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-04T12:44:04.251857Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1292040352,"revision":19909,"compact-revision":17489} {"level":"info","ts":"2026-06-04T12:44:38.922929Z","caller":"traceutil/trace.go:171","msg":"trace[1094458649] linearizableReadLoop","detail":"{readStateIndex:23662; appliedIndex:23661; }","duration":"147.502718ms","start":"2026-06-04T12:44:38.775401Z","end":"2026-06-04T12:44:38.922904Z","steps":["trace[1094458649] 'read index received' (duration: 146.923915ms)","trace[1094458649] 'applied index is now lower than readState.Index' (duration: 577.523µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:44:38.923093Z","caller":"traceutil/trace.go:171","msg":"trace[2047118990] transaction","detail":"{read_only:false; response_revision:22885; number_of_response:1; }","duration":"226.277448ms","start":"2026-06-04T12:44:38.696782Z","end":"2026-06-04T12:44:38.923059Z","steps":["trace[2047118990] 'process raft request' (duration: 225.558261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:44:38.923223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.744941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:44:38.923339Z","caller":"traceutil/trace.go:171","msg":"trace[128563147] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:22885; }","duration":"111.906965ms","start":"2026-06-04T12:44:38.811405Z","end":"2026-06-04T12:44:38.923312Z","steps":["trace[128563147] 'agreement among raft nodes before linearized reading' (duration: 111.69232ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:44:38.923268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.820787ms","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-06-04T12:44:38.923551Z","caller":"traceutil/trace.go:171","msg":"trace[1748586870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22885; }","duration":"148.199686ms","start":"2026-06-04T12:44:38.775343Z","end":"2026-06-04T12:44:38.923543Z","steps":["trace[1748586870] 'agreement among raft nodes before linearized reading' (duration: 147.714554ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:44:39.072058Z","caller":"traceutil/trace.go:171","msg":"trace[1746761125] transaction","detail":"{read_only:false; response_revision:22889; number_of_response:1; }","duration":"105.589233ms","start":"2026-06-04T12:44:38.966449Z","end":"2026-06-04T12:44:39.072039Z","steps":["trace[1746761125] 'process raft request' (duration: 105.536872ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:44:39.0721Z","caller":"traceutil/trace.go:171","msg":"trace[2087539986] transaction","detail":"{read_only:false; response_revision:22888; number_of_response:1; }","duration":"128.115664ms","start":"2026-06-04T12:44:38.943938Z","end":"2026-06-04T12:44:39.072054Z","steps":["trace[2087539986] 'process raft request' (duration: 127.757855ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:11.383731Z","caller":"traceutil/trace.go:171","msg":"trace[2099309865] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"138.716619ms","start":"2026-06-04T12:48:11.244971Z","end":"2026-06-04T12:48:11.383687Z","steps":["trace[2099309865] 'process raft request' (duration: 138.460423ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:15.976112Z","caller":"traceutil/trace.go:171","msg":"trace[1067955127] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"121.840444ms","start":"2026-06-04T12:48:15.854233Z","end":"2026-06-04T12:48:15.976074Z","steps":["trace[1067955127] 'process raft request' (duration: 25.78507ms)","trace[1067955127] 'compare' (duration: 95.9064ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:17.181107Z","caller":"traceutil/trace.go:171","msg":"trace[437858219] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"129.644762ms","start":"2026-06-04T12:48:17.051435Z","end":"2026-06-04T12:48:17.18108Z","steps":["trace[437858219] 'process raft request' (duration: 129.436737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:17.33334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.772898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:17.333428Z","caller":"traceutil/trace.go:171","msg":"trace[1250760441] linearizableReadLoop","detail":"{readStateIndex:25918; appliedIndex:25916; }","duration":"177.721249ms","start":"2026-06-04T12:48:17.155692Z","end":"2026-06-04T12:48:17.333413Z","steps":["trace[1250760441] 'read index received' (duration: 25.176806ms)","trace[1250760441] 'applied index is now lower than readState.Index' (duration: 152.543713ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:17.333599Z","caller":"traceutil/trace.go:171","msg":"trace[411075277] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"243.553614ms","start":"2026-06-04T12:48:17.090035Z","end":"2026-06-04T12:48:17.333589Z","steps":["trace[411075277] 'process raft request' (duration: 117.455258ms)","trace[411075277] 'compare' (duration: 125.679506ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:17.333939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.871555ms","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-06-04T12:48:17.334001Z","caller":"traceutil/trace.go:171","msg":"trace[85053159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25086; }","duration":"153.982987ms","start":"2026-06-04T12:48:17.180003Z","end":"2026-06-04T12:48:17.333986Z","steps":["trace[85053159] 'agreement among raft nodes before linearized reading' (duration: 153.827073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:17.334042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.353103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274798"} {"level":"info","ts":"2026-06-04T12:48:17.334069Z","caller":"traceutil/trace.go:171","msg":"trace[2121340669] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25086; }","duration":"178.429645ms","start":"2026-06-04T12:48:17.155632Z","end":"2026-06-04T12:48:17.334061Z","steps":["trace[2121340669] 'agreement among raft nodes before linearized reading' (duration: 178.092917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:17.334338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.381444ms","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-06-04T12:48:17.334379Z","caller":"traceutil/trace.go:171","msg":"trace[997229236] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25086; }","duration":"124.490896ms","start":"2026-06-04T12:48:17.209878Z","end":"2026-06-04T12:48:17.334369Z","steps":["trace[997229236] 'agreement among raft nodes before linearized reading' (duration: 124.395254ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:17.488043Z","caller":"traceutil/trace.go:171","msg":"trace[630813635] linearizableReadLoop","detail":"{readStateIndex:25920; appliedIndex:25918; }","duration":"143.234289ms","start":"2026-06-04T12:48:17.344784Z","end":"2026-06-04T12:48:17.488018Z","steps":["trace[630813635] 'read index received' (duration: 30.179557ms)","trace[630813635] 'applied index is now lower than readState.Index' (duration: 113.053672ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:17.488182Z","caller":"traceutil/trace.go:171","msg":"trace[1927427895] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"149.94618ms","start":"2026-06-04T12:48:17.33817Z","end":"2026-06-04T12:48:17.488116Z","steps":["trace[1927427895] 'process raft request' (duration: 149.642283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:17.48829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.490625ms","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-06-04T12:48:17.488357Z","caller":"traceutil/trace.go:171","msg":"trace[886209310] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25087; }","duration":"143.593267ms","start":"2026-06-04T12:48:17.344747Z","end":"2026-06-04T12:48:17.488341Z","steps":["trace[886209310] 'agreement among raft nodes before linearized reading' (duration: 143.423813ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:17.488652Z","caller":"traceutil/trace.go:171","msg":"trace[2053641052] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"111.880503ms","start":"2026-06-04T12:48:17.376759Z","end":"2026-06-04T12:48:17.488639Z","steps":["trace[2053641052] 'process raft request' (duration: 111.623566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:17.488655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.640942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:17.488718Z","caller":"traceutil/trace.go:171","msg":"trace[229254468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25089; }","duration":"129.746624ms","start":"2026-06-04T12:48:17.358956Z","end":"2026-06-04T12:48:17.488703Z","steps":["trace[229254468] 'agreement among raft nodes before linearized reading' (duration: 129.613971ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:21.57611Z","caller":"traceutil/trace.go:171","msg":"trace[1206674393] linearizableReadLoop","detail":"{readStateIndex:25947; appliedIndex:25946; }","duration":"216.331139ms","start":"2026-06-04T12:48:21.359747Z","end":"2026-06-04T12:48:21.576078Z","steps":["trace[1206674393] 'read index received' (duration: 216.154955ms)","trace[1206674393] 'applied index is now lower than readState.Index' (duration: 174.684µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:21.576289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.522734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:21.576328Z","caller":"traceutil/trace.go:171","msg":"trace[1944610750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25114; }","duration":"216.626437ms","start":"2026-06-04T12:48:21.359691Z","end":"2026-06-04T12:48:21.576317Z","steps":["trace[1944610750] 'agreement among raft nodes before linearized reading' (duration: 216.532024ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:21.576271Z","caller":"traceutil/trace.go:171","msg":"trace[1351579806] transaction","detail":"{read_only:false; response_revision:25114; number_of_response:1; }","duration":"219.607968ms","start":"2026-06-04T12:48:21.356604Z","end":"2026-06-04T12:48:21.576212Z","steps":["trace[1351579806] 'process raft request' (duration: 219.290001ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:21.669618Z","caller":"traceutil/trace.go:171","msg":"trace[1288223428] transaction","detail":"{read_only:false; response_revision:25115; number_of_response:1; }","duration":"164.758637ms","start":"2026-06-04T12:48:21.504838Z","end":"2026-06-04T12:48:21.669596Z","steps":["trace[1288223428] 'process raft request' (duration: 162.948623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:21.669882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.900478ms","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-06-04T12:48:21.669913Z","caller":"traceutil/trace.go:171","msg":"trace[737689058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25115; }","duration":"161.945899ms","start":"2026-06-04T12:48:21.507959Z","end":"2026-06-04T12:48:21.669905Z","steps":["trace[737689058] 'agreement among raft nodes before linearized reading' (duration: 161.830947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:21.670387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.43128ms","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-06-04T12:48:21.670566Z","caller":"traceutil/trace.go:171","msg":"trace[862005928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25115; }","duration":"167.668556ms","start":"2026-06-04T12:48:21.502868Z","end":"2026-06-04T12:48:21.670536Z","steps":["trace[862005928] 'agreement among raft nodes before linearized reading' (duration: 167.301648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:21.955955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.468118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:21.956518Z","caller":"traceutil/trace.go:171","msg":"trace[899737970] linearizableReadLoop","detail":"{readStateIndex:25952; appliedIndex:25949; }","duration":"128.070773ms","start":"2026-06-04T12:48:21.828429Z","end":"2026-06-04T12:48:21.9565Z","steps":["trace[899737970] 'read index received' (duration: 123.916783ms)","trace[899737970] 'applied index is now lower than readState.Index' (duration: 4.153ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:21.956778Z","caller":"traceutil/trace.go:171","msg":"trace[922640014] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"146.228321ms","start":"2026-06-04T12:48:21.810536Z","end":"2026-06-04T12:48:21.956764Z","steps":["trace[922640014] 'process raft request' (duration: 145.921633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:21.956982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.549395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:21.956523Z","caller":"traceutil/trace.go:171","msg":"trace[1136429011] transaction","detail":"{read_only:false; response_revision:25117; number_of_response:1; }","duration":"282.197204ms","start":"2026-06-04T12:48:21.674287Z","end":"2026-06-04T12:48:21.956484Z","steps":["trace[1136429011] 'process raft request' (duration: 137.112341ms)","trace[1136429011] 'compare' (duration: 144.192691ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:21.957146Z","caller":"traceutil/trace.go:171","msg":"trace[1951693730] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25119; }","duration":"128.690529ms","start":"2026-06-04T12:48:21.828382Z","end":"2026-06-04T12:48:21.957073Z","steps":["trace[1951693730] 'agreement among raft nodes before linearized reading' (duration: 128.557145ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:21.956646Z","caller":"traceutil/trace.go:171","msg":"trace[159609562] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"279.358245ms","start":"2026-06-04T12:48:21.677264Z","end":"2026-06-04T12:48:21.956622Z","steps":["trace[159609562] 'process raft request' (duration: 279.093639ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:22.166871Z","caller":"traceutil/trace.go:171","msg":"trace[526825682] transaction","detail":"{read_only:false; response_revision:25120; number_of_response:1; }","duration":"205.677632ms","start":"2026-06-04T12:48:21.961161Z","end":"2026-06-04T12:48:22.166839Z","steps":["trace[526825682] 'process raft request' (duration: 130.266767ms)","trace[526825682] 'compare' (duration: 75.062806ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:22.166921Z","caller":"traceutil/trace.go:171","msg":"trace[74617455] transaction","detail":"{read_only:false; response_revision:25122; number_of_response:1; }","duration":"159.040919ms","start":"2026-06-04T12:48:22.007828Z","end":"2026-06-04T12:48:22.166869Z","steps":["trace[74617455] 'process raft request' (duration: 158.933205ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:22.166955Z","caller":"traceutil/trace.go:171","msg":"trace[585579123] transaction","detail":"{read_only:false; response_revision:25121; number_of_response:1; }","duration":"165.728891ms","start":"2026-06-04T12:48:22.001195Z","end":"2026-06-04T12:48:22.166924Z","steps":["trace[585579123] 'process raft request' (duration: 165.498075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:22.420803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.293462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:22.420927Z","caller":"traceutil/trace.go:171","msg":"trace[1434216738] transaction","detail":"{read_only:false; response_revision:25123; number_of_response:1; }","duration":"249.368554ms","start":"2026-06-04T12:48:22.171537Z","end":"2026-06-04T12:48:22.420905Z","steps":["trace[1434216738] 'process raft request' (duration: 134.877577ms)","trace[1434216738] 'compare' (duration: 113.739189ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:22.422316Z","caller":"traceutil/trace.go:171","msg":"trace[109361112] transaction","detail":"{read_only:false; response_revision:25124; number_of_response:1; }","duration":"250.528312ms","start":"2026-06-04T12:48:22.171745Z","end":"2026-06-04T12:48:22.422273Z","steps":["trace[109361112] 'process raft request' (duration: 250.298377ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:22.607578Z","caller":"traceutil/trace.go:171","msg":"trace[1483971529] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"152.769579ms","start":"2026-06-04T12:48:22.454784Z","end":"2026-06-04T12:48:22.607554Z","steps":["trace[1483971529] 'process raft request' (duration: 152.627566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:23.503147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.547295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:23.503216Z","caller":"traceutil/trace.go:171","msg":"trace[210948979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25127; }","duration":"140.656918ms","start":"2026-06-04T12:48:23.362543Z","end":"2026-06-04T12:48:23.5032Z","steps":["trace[210948979] 'range keys from in-memory index tree' (duration: 140.455392ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:23.686393Z","caller":"traceutil/trace.go:171","msg":"trace[521999029] transaction","detail":"{read_only:false; response_revision:25128; number_of_response:1; }","duration":"104.104486ms","start":"2026-06-04T12:48:23.582262Z","end":"2026-06-04T12:48:23.686366Z","steps":["trace[521999029] 'process raft request' (duration: 103.910182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:23.882847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.227778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:23.88295Z","caller":"traceutil/trace.go:171","msg":"trace[1639768080] linearizableReadLoop","detail":"{readStateIndex:25963; appliedIndex:25962; }","duration":"196.606944ms","start":"2026-06-04T12:48:23.686327Z","end":"2026-06-04T12:48:23.882934Z","steps":["trace[1639768080] 'read index received' (duration: 31.200371ms)","trace[1639768080] 'applied index is now lower than readState.Index' (duration: 165.405583ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:23.883041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.138694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:23.88307Z","caller":"traceutil/trace.go:171","msg":"trace[452992051] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25129; }","duration":"253.208906ms","start":"2026-06-04T12:48:23.629852Z","end":"2026-06-04T12:48:23.88306Z","steps":["trace[452992051] 'agreement among raft nodes before linearized reading' (duration: 253.151044ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:23.883038Z","caller":"traceutil/trace.go:171","msg":"trace[969666465] transaction","detail":"{read_only:false; response_revision:25129; number_of_response:1; }","duration":"206.465901ms","start":"2026-06-04T12:48:23.676495Z","end":"2026-06-04T12:48:23.882961Z","steps":["trace[969666465] 'process raft request' (duration: 41.019128ms)","trace[969666465] 'compare' (duration: 164.839219ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:24.029471Z","caller":"traceutil/trace.go:171","msg":"trace[1407466052] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"321.796907ms","start":"2026-06-04T12:48:23.707648Z","end":"2026-06-04T12:48:24.029445Z","steps":["trace[1407466052] 'process raft request' (duration: 309.054171ms)","trace[1407466052] 'compare' (duration: 12.646054ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:24.029598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:23.707623Z","time spent":"321.906409ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:24.040274Z","caller":"traceutil/trace.go:171","msg":"trace[1462804468] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"221.972763ms","start":"2026-06-04T12:48:23.81828Z","end":"2026-06-04T12:48:24.040252Z","steps":["trace[1462804468] 'process raft request' (duration: 221.82209ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:24.040394Z","caller":"traceutil/trace.go:171","msg":"trace[587321325] linearizableReadLoop","detail":"{readStateIndex:25965; appliedIndex:25963; }","duration":"157.402929ms","start":"2026-06-04T12:48:23.882976Z","end":"2026-06-04T12:48:24.040379Z","steps":["trace[587321325] 'read index received' (duration: 133.74318ms)","trace[587321325] 'applied index is now lower than readState.Index' (duration: 23.658619ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:24.040712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.072554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:24.041663Z","caller":"traceutil/trace.go:171","msg":"trace[1032994847] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25132; }","duration":"350.057048ms","start":"2026-06-04T12:48:23.691569Z","end":"2026-06-04T12:48:24.041626Z","steps":["trace[1032994847] 'agreement among raft nodes before linearized reading' (duration: 349.084394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.041743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:23.691551Z","time spent":"350.178651ms","remote":"127.0.0.1:50442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-04T12:48:24.321798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.664145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:24.321933Z","caller":"traceutil/trace.go:171","msg":"trace[738608356] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"277.603504ms","start":"2026-06-04T12:48:24.044306Z","end":"2026-06-04T12:48:24.321909Z","steps":["trace[738608356] 'process raft request' (duration: 129.734314ms)","trace[738608356] 'compare' (duration: 147.538412ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:24.335995Z","caller":"traceutil/trace.go:171","msg":"trace[1211840251] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"291.219172ms","start":"2026-06-04T12:48:24.044758Z","end":"2026-06-04T12:48:24.335977Z","steps":["trace[1211840251] 'process raft request' (duration: 291.077179ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:24.336192Z","caller":"traceutil/trace.go:171","msg":"trace[824730966] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"162.722088ms","start":"2026-06-04T12:48:24.173463Z","end":"2026-06-04T12:48:24.336185Z","steps":["trace[824730966] 'process raft request' (duration: 162.480162ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:24.336319Z","caller":"traceutil/trace.go:171","msg":"trace[279873290] linearizableReadLoop","detail":"{readStateIndex:25968; appliedIndex:25966; }","duration":"290.270559ms","start":"2026-06-04T12:48:24.04604Z","end":"2026-06-04T12:48:24.336311Z","steps":["trace[279873290] 'read index received' (duration: 128.075004ms)","trace[279873290] 'applied index is now lower than readState.Index' (duration: 162.194435ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:24.336378Z","caller":"traceutil/trace.go:171","msg":"trace[129996781] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"162.859501ms","start":"2026-06-04T12:48:24.173513Z","end":"2026-06-04T12:48:24.336372Z","steps":["trace[129996781] 'process raft request' (duration: 162.631796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.336563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.503895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:24.336596Z","caller":"traceutil/trace.go:171","msg":"trace[913751371] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25136; }","duration":"290.572106ms","start":"2026-06-04T12:48:24.046014Z","end":"2026-06-04T12:48:24.336586Z","steps":["trace[913751371] 'agreement among raft nodes before linearized reading' (duration: 290.503395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.336792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.408303ms","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-06-04T12:48:24.336859Z","caller":"traceutil/trace.go:171","msg":"trace[1163242250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25136; }","duration":"165.519165ms","start":"2026-06-04T12:48:24.171323Z","end":"2026-06-04T12:48:24.336842Z","steps":["trace[1163242250] 'agreement among raft nodes before linearized reading' (duration: 165.319111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.336858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.087193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:24.336923Z","caller":"traceutil/trace.go:171","msg":"trace[2039343927] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25136; }","duration":"226.193126ms","start":"2026-06-04T12:48:24.110711Z","end":"2026-06-04T12:48:24.336904Z","steps":["trace[2039343927] 'agreement among raft nodes before linearized reading' (duration: 225.898379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.629725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.609263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:24.629951Z","caller":"traceutil/trace.go:171","msg":"trace[1642155092] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"157.499412ms","start":"2026-06-04T12:48:24.472432Z","end":"2026-06-04T12:48:24.629932Z","steps":["trace[1642155092] 'process raft request' (duration: 157.41706ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:24.630331Z","caller":"traceutil/trace.go:171","msg":"trace[1197028530] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"289.365426ms","start":"2026-06-04T12:48:24.34095Z","end":"2026-06-04T12:48:24.630316Z","steps":["trace[1197028530] 'process raft request' (duration: 146.078707ms)","trace[1197028530] 'compare' (duration: 142.45953ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:24.630509Z","caller":"traceutil/trace.go:171","msg":"trace[2127343862] linearizableReadLoop","detail":"{readStateIndex:25971; appliedIndex:25970; }","duration":"286.843176ms","start":"2026-06-04T12:48:24.343653Z","end":"2026-06-04T12:48:24.630496Z","steps":["trace[2127343862] 'read index received' (duration: 143.420583ms)","trace[2127343862] 'applied index is now lower than readState.Index' (duration: 143.421453ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:24.630752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.097991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:24.630794Z","caller":"traceutil/trace.go:171","msg":"trace[1375847773] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25138; }","duration":"287.169643ms","start":"2026-06-04T12:48:24.343613Z","end":"2026-06-04T12:48:24.630782Z","steps":["trace[1375847773] 'agreement among raft nodes before linearized reading' (duration: 287.096532ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.631124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.222571ms","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-06-04T12:48:24.63118Z","caller":"traceutil/trace.go:171","msg":"trace[1851075952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25138; }","duration":"205.317913ms","start":"2026-06-04T12:48:24.425847Z","end":"2026-06-04T12:48:24.631165Z","steps":["trace[1851075952] 'agreement among raft nodes before linearized reading' (duration: 205.16171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.631384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.586898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-04T12:48:24.631428Z","caller":"traceutil/trace.go:171","msg":"trace[1925200088] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25138; }","duration":"200.66175ms","start":"2026-06-04T12:48:24.430752Z","end":"2026-06-04T12:48:24.631413Z","steps":["trace[1925200088] 'agreement among raft nodes before linearized reading' (duration: 200.513707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:24.631593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.62676ms","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-06-04T12:48:24.631671Z","caller":"traceutil/trace.go:171","msg":"trace[817485497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25138; }","duration":"205.735183ms","start":"2026-06-04T12:48:24.425918Z","end":"2026-06-04T12:48:24.631653Z","steps":["trace[817485497] 'agreement among raft nodes before linearized reading' (duration: 205.512798ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:25.020495Z","caller":"traceutil/trace.go:171","msg":"trace[1682007077] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"383.115545ms","start":"2026-06-04T12:48:24.63736Z","end":"2026-06-04T12:48:25.020476Z","steps":["trace[1682007077] 'process raft request' (duration: 382.967621ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:25.020609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:24.637342Z","time spent":"383.206296ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:25.020652Z","caller":"traceutil/trace.go:171","msg":"trace[894358656] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25973; }","duration":"322.817231ms","start":"2026-06-04T12:48:24.697813Z","end":"2026-06-04T12:48:25.020631Z","steps":["trace[894358656] 'read index received' (duration: 322.493474ms)","trace[894358656] 'applied index is now lower than readState.Index' (duration: 321.677µs)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:25.020732Z","caller":"traceutil/trace.go:171","msg":"trace[1866538323] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"383.323039ms","start":"2026-06-04T12:48:24.637369Z","end":"2026-06-04T12:48:25.020692Z","steps":["trace[1866538323] 'process raft request' (duration: 383.076813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:25.020801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.963706ms","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-06-04T12:48:25.020866Z","caller":"traceutil/trace.go:171","msg":"trace[1280207568] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25141; }","duration":"323.089549ms","start":"2026-06-04T12:48:24.697762Z","end":"2026-06-04T12:48:25.020851Z","steps":["trace[1280207568] 'agreement among raft nodes before linearized reading' (duration: 322.940655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:25.0209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:24.697735Z","time spent":"323.1595ms","remote":"127.0.0.1:50722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-04T12:48:25.020927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:24.637314Z","time spent":"383.512304ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:25.375684Z","caller":"traceutil/trace.go:171","msg":"trace[364848443] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"126.590578ms","start":"2026-06-04T12:48:25.249072Z","end":"2026-06-04T12:48:25.375662Z","steps":["trace[364848443] 'process raft request' (duration: 126.423394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:25.667139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.930267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:25.667247Z","caller":"traceutil/trace.go:171","msg":"trace[1135781745] linearizableReadLoop","detail":"{readStateIndex:25977; appliedIndex:25975; }","duration":"318.829756ms","start":"2026-06-04T12:48:25.348399Z","end":"2026-06-04T12:48:25.667229Z","steps":["trace[1135781745] 'read index received' (duration: 27.108373ms)","trace[1135781745] 'applied index is now lower than readState.Index' (duration: 291.720003ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:25.667397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.9833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:25.667395Z","caller":"traceutil/trace.go:171","msg":"trace[1467300793] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"394.812176ms","start":"2026-06-04T12:48:25.272534Z","end":"2026-06-04T12:48:25.667347Z","steps":["trace[1467300793] 'process raft request' (duration: 247.574952ms)","trace[1467300793] 'compare' (duration: 146.616799ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:25.667437Z","caller":"traceutil/trace.go:171","msg":"trace[1998800282] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25143; }","duration":"319.033231ms","start":"2026-06-04T12:48:25.348392Z","end":"2026-06-04T12:48:25.667425Z","steps":["trace[1998800282] 'agreement among raft nodes before linearized reading' (duration: 318.944329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:25.66748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:25.348371Z","time spent":"319.098173ms","remote":"127.0.0.1:57074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:48:25.667543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:25.27252Z","time spent":"394.940899ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:25.667409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.300779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:25.667644Z","caller":"traceutil/trace.go:171","msg":"trace[64340027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25143; }","duration":"307.551584ms","start":"2026-06-04T12:48:25.360065Z","end":"2026-06-04T12:48:25.667616Z","steps":["trace[64340027] 'agreement among raft nodes before linearized reading' (duration: 307.297868ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:25.667723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:25.360054Z","time spent":"307.641767ms","remote":"127.0.0.1:50246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:48:25.925892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.657106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:25.926036Z","caller":"traceutil/trace.go:171","msg":"trace[364455234] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"234.591457ms","start":"2026-06-04T12:48:25.691421Z","end":"2026-06-04T12:48:25.926012Z","steps":["trace[364455234] 'process raft request' (duration: 130.720427ms)","trace[364455234] 'compare' (duration: 103.530572ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:26.017799Z","caller":"traceutil/trace.go:171","msg":"trace[1972875806] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"129.759504ms","start":"2026-06-04T12:48:25.888004Z","end":"2026-06-04T12:48:26.017764Z","steps":["trace[1972875806] 'process raft request' (duration: 129.563849ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:26.212282Z","caller":"traceutil/trace.go:171","msg":"trace[2145055661] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"175.302901ms","start":"2026-06-04T12:48:26.036951Z","end":"2026-06-04T12:48:26.212254Z","steps":["trace[2145055661] 'process raft request' (duration: 175.118147ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:26.253626Z","caller":"traceutil/trace.go:171","msg":"trace[984842666] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"207.991057ms","start":"2026-06-04T12:48:26.045611Z","end":"2026-06-04T12:48:26.253602Z","steps":["trace[984842666] 'process raft request' (duration: 207.874985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:26.535095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.89824ms","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-06-04T12:48:26.535156Z","caller":"traceutil/trace.go:171","msg":"trace[1532892556] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25147; }","duration":"209.004183ms","start":"2026-06-04T12:48:26.326138Z","end":"2026-06-04T12:48:26.535142Z","steps":["trace[1532892556] 'range keys from in-memory index tree' (duration: 208.757617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:26.535359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.528224ms","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-06-04T12:48:26.535381Z","caller":"traceutil/trace.go:171","msg":"trace[1693763950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25147; }","duration":"194.578375ms","start":"2026-06-04T12:48:26.340797Z","end":"2026-06-04T12:48:26.535376Z","steps":["trace[1693763950] 'range keys from in-memory index tree' (duration: 194.431501ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:26.535999Z","caller":"traceutil/trace.go:171","msg":"trace[1236679190] transaction","detail":"{read_only:false; response_revision:25148; number_of_response:1; }","duration":"194.522153ms","start":"2026-06-04T12:48:26.341455Z","end":"2026-06-04T12:48:26.535977Z","steps":["trace[1236679190] 'process raft request' (duration: 170.67266ms)","trace[1236679190] 'compare' (duration: 22.820359ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:26.536069Z","caller":"traceutil/trace.go:171","msg":"trace[1393907740] linearizableReadLoop","detail":"{readStateIndex:25983; appliedIndex:25981; }","duration":"174.563362ms","start":"2026-06-04T12:48:26.361496Z","end":"2026-06-04T12:48:26.53606Z","steps":["trace[1393907740] 'read index received' (duration: 150.713199ms)","trace[1393907740] 'applied index is now lower than readState.Index' (duration: 23.849583ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:26.536123Z","caller":"traceutil/trace.go:171","msg":"trace[400246284] transaction","detail":"{read_only:false; response_revision:25149; number_of_response:1; }","duration":"193.670023ms","start":"2026-06-04T12:48:26.342447Z","end":"2026-06-04T12:48:26.536117Z","steps":["trace[400246284] 'process raft request' (duration: 193.482748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:26.5362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.707965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:48:26.536194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.131082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:26.536221Z","caller":"traceutil/trace.go:171","msg":"trace[79876013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25149; }","duration":"174.762637ms","start":"2026-06-04T12:48:26.361454Z","end":"2026-06-04T12:48:26.536216Z","steps":["trace[79876013] 'agreement among raft nodes before linearized reading' (duration: 174.724706ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:26.536234Z","caller":"traceutil/trace.go:171","msg":"trace[1019841701] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25149; }","duration":"142.200034ms","start":"2026-06-04T12:48:26.394021Z","end":"2026-06-04T12:48:26.536221Z","steps":["trace[1019841701] 'agreement among raft nodes before linearized reading' (duration: 142.136583ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:26.705566Z","caller":"traceutil/trace.go:171","msg":"trace[1905964667] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"165.927046ms","start":"2026-06-04T12:48:26.539608Z","end":"2026-06-04T12:48:26.705535Z","steps":["trace[1905964667] 'process raft request' (duration: 149.284825ms)","trace[1905964667] 'compare' (duration: 16.506058ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:26.858513Z","caller":"traceutil/trace.go:171","msg":"trace[557171626] linearizableReadLoop","detail":"{readStateIndex:25985; appliedIndex:25983; }","duration":"221.759849ms","start":"2026-06-04T12:48:26.636727Z","end":"2026-06-04T12:48:26.858487Z","steps":["trace[557171626] 'read index received' (duration: 52.176497ms)","trace[557171626] 'applied index is now lower than readState.Index' (duration: 169.582442ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:26.858586Z","caller":"traceutil/trace.go:171","msg":"trace[1168390913] transaction","detail":"{read_only:false; response_revision:25151; number_of_response:1; }","duration":"318.792745ms","start":"2026-06-04T12:48:26.539761Z","end":"2026-06-04T12:48:26.858554Z","steps":["trace[1168390913] 'process raft request' (duration: 267.310655ms)","trace[1168390913] 'compare' (duration: 51.296685ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:26.858748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:26.539745Z","time spent":"318.917227ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:26.858753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.932282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:48:26.85879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.038585ms","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-06-04T12:48:26.858808Z","caller":"traceutil/trace.go:171","msg":"trace[1148470069] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25151; }","duration":"207.021804ms","start":"2026-06-04T12:48:26.651772Z","end":"2026-06-04T12:48:26.858794Z","steps":["trace[1148470069] 'agreement among raft nodes before linearized reading' (duration: 206.922022ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:26.858835Z","caller":"traceutil/trace.go:171","msg":"trace[1695604900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25151; }","duration":"222.138618ms","start":"2026-06-04T12:48:26.636684Z","end":"2026-06-04T12:48:26.858822Z","steps":["trace[1695604900] 'agreement among raft nodes before linearized reading' (duration: 221.939013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:26.858988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.853337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-04T12:48:26.859047Z","caller":"traceutil/trace.go:171","msg":"trace[1299423647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25151; }","duration":"156.915278ms","start":"2026-06-04T12:48:26.702118Z","end":"2026-06-04T12:48:26.859034Z","steps":["trace[1299423647] 'agreement among raft nodes before linearized reading' (duration: 156.726914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:27.203789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.602088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:27.204191Z","caller":"traceutil/trace.go:171","msg":"trace[1025369677] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"339.195116ms","start":"2026-06-04T12:48:26.864965Z","end":"2026-06-04T12:48:27.20416Z","steps":["trace[1025369677] 'process raft request' (duration: 163.120437ms)","trace[1025369677] 'compare' (duration: 175.240598ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:27.204296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:26.864943Z","time spent":"339.306139ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:27.204924Z","caller":"traceutil/trace.go:171","msg":"trace[465044328] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"336.167054ms","start":"2026-06-04T12:48:26.86873Z","end":"2026-06-04T12:48:27.204897Z","steps":["trace[465044328] 'process raft request' (duration: 335.379295ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:27.205097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:26.868711Z","time spent":"336.306216ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:27.205102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.911351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-04T12:48:27.205178Z","caller":"traceutil/trace.go:171","msg":"trace[1459483962] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25153; }","duration":"192.045984ms","start":"2026-06-04T12:48:27.013115Z","end":"2026-06-04T12:48:27.205161Z","steps":["trace[1459483962] 'agreement among raft nodes before linearized reading' (duration: 191.8711ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:27.204829Z","caller":"traceutil/trace.go:171","msg":"trace[1661091162] linearizableReadLoop","detail":"{readStateIndex:25987; appliedIndex:25985; }","duration":"191.638994ms","start":"2026-06-04T12:48:27.013173Z","end":"2026-06-04T12:48:27.204812Z","steps":["trace[1661091162] 'read index received' (duration: 14.935209ms)","trace[1661091162] 'applied index is now lower than readState.Index' (duration: 176.702485ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:27.20544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.515978ms","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-06-04T12:48:27.205498Z","caller":"traceutil/trace.go:171","msg":"trace[776389589] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25153; }","duration":"178.59773ms","start":"2026-06-04T12:48:27.026881Z","end":"2026-06-04T12:48:27.205479Z","steps":["trace[776389589] 'agreement among raft nodes before linearized reading' (duration: 178.429556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:27.205501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.526789ms","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-06-04T12:48:27.205547Z","caller":"traceutil/trace.go:171","msg":"trace[50556233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25153; }","duration":"178.57225ms","start":"2026-06-04T12:48:27.026963Z","end":"2026-06-04T12:48:27.205535Z","steps":["trace[50556233] 'agreement among raft nodes before linearized reading' (duration: 178.436817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:27.512147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.098729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:27.512495Z","caller":"traceutil/trace.go:171","msg":"trace[1868183735] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"131.550407ms","start":"2026-06-04T12:48:27.380916Z","end":"2026-06-04T12:48:27.512467Z","steps":["trace[1868183735] 'process raft request' (duration: 131.498586ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:27.512658Z","caller":"traceutil/trace.go:171","msg":"trace[324716880] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"299.9015ms","start":"2026-06-04T12:48:27.212747Z","end":"2026-06-04T12:48:27.512648Z","steps":["trace[324716880] 'process raft request' (duration: 299.537732ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:27.512667Z","caller":"traceutil/trace.go:171","msg":"trace[536029928] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"301.219063ms","start":"2026-06-04T12:48:27.211423Z","end":"2026-06-04T12:48:27.512642Z","steps":["trace[536029928] 'process raft request' (duration: 139.461178ms)","trace[536029928] 'compare' (duration: 160.718149ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:27.512762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.97873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:27.512794Z","caller":"traceutil/trace.go:171","msg":"trace[1992447119] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:25156; }","duration":"287.059781ms","start":"2026-06-04T12:48:27.225724Z","end":"2026-06-04T12:48:27.512784Z","steps":["trace[1992447119] 'agreement among raft nodes before linearized reading' (duration: 286.98747ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:27.512665Z","caller":"traceutil/trace.go:171","msg":"trace[654619480] linearizableReadLoop","detail":"{readStateIndex:25989; appliedIndex:25987; }","duration":"286.876277ms","start":"2026-06-04T12:48:27.225769Z","end":"2026-06-04T12:48:27.512645Z","steps":["trace[654619480] 'read index received' (duration: 125.126943ms)","trace[654619480] 'applied index is now lower than readState.Index' (duration: 161.747644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:27.512811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:27.211407Z","time spent":"301.322405ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:27.512953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.842431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:27.512982Z","caller":"traceutil/trace.go:171","msg":"trace[818509193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25156; }","duration":"145.900453ms","start":"2026-06-04T12:48:27.367071Z","end":"2026-06-04T12:48:27.512972Z","steps":["trace[818509193] 'agreement among raft nodes before linearized reading' (duration: 145.831591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:27.513025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.548582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-06-04T12:48:27.513086Z","caller":"traceutil/trace.go:171","msg":"trace[847987] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:25156; }","duration":"267.649264ms","start":"2026-06-04T12:48:27.245421Z","end":"2026-06-04T12:48:27.51307Z","steps":["trace[847987] 'agreement among raft nodes before linearized reading' (duration: 267.4603ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:36.295119Z","caller":"traceutil/trace.go:171","msg":"trace[72405223] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"326.208235ms","start":"2026-06-04T12:48:35.968885Z","end":"2026-06-04T12:48:36.295094Z","steps":["trace[72405223] 'process raft request' (duration: 326.01293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:36.295286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:35.968862Z","time spent":"326.335717ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:36.807357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.62966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:36.80759Z","caller":"traceutil/trace.go:171","msg":"trace[1740795242] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"797.671195ms","start":"2026-06-04T12:48:36.009889Z","end":"2026-06-04T12:48:36.807561Z","steps":["trace[1740795242] 'process raft request' (duration: 520.727917ms)","trace[1740795242] 'compare' (duration: 276.401955ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:36.80771Z","caller":"traceutil/trace.go:171","msg":"trace[375239025] linearizableReadLoop","detail":"{readStateIndex:26056; appliedIndex:26052; }","duration":"448.100658ms","start":"2026-06-04T12:48:36.359587Z","end":"2026-06-04T12:48:36.807688Z","steps":["trace[375239025] 'read index received' (duration: 171.165201ms)","trace[375239025] 'applied index is now lower than readState.Index' (duration: 276.934347ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:36.807738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.009858Z","time spent":"797.796338ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:36.807942Z","caller":"traceutil/trace.go:171","msg":"trace[478382608] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"746.528884ms","start":"2026-06-04T12:48:36.061396Z","end":"2026-06-04T12:48:36.807925Z","steps":["trace[478382608] 'process raft request' (duration: 746.067643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:36.808034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.06138Z","time spent":"746.604146ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:36.808069Z","caller":"traceutil/trace.go:171","msg":"trace[1439202718] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"493.803518ms","start":"2026-06-04T12:48:36.314238Z","end":"2026-06-04T12:48:36.808042Z","steps":["trace[1439202718] 'process raft request' (duration: 493.398079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:36.808127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.314206Z","time spent":"493.891171ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:36.808181Z","caller":"traceutil/trace.go:171","msg":"trace[1806726059] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"547.587863ms","start":"2026-06-04T12:48:36.260568Z","end":"2026-06-04T12:48:36.808156Z","steps":["trace[1806726059] 'process raft request' (duration: 547.003109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:36.808192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.57515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:48:36.808286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.260542Z","time spent":"547.686626ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:36.808286Z","caller":"traceutil/trace.go:171","msg":"trace[450737455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25220; }","duration":"448.713703ms","start":"2026-06-04T12:48:36.359548Z","end":"2026-06-04T12:48:36.808261Z","steps":["trace[450737455] 'agreement among raft nodes before linearized reading' (duration: 448.532088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:36.80834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.359529Z","time spent":"448.799545ms","remote":"127.0.0.1:50246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-04T12:48:37.274151Z","caller":"traceutil/trace.go:171","msg":"trace[529912102] linearizableReadLoop","detail":"{readStateIndex:26058; appliedIndex:26056; }","duration":"466.370208ms","start":"2026-06-04T12:48:36.807757Z","end":"2026-06-04T12:48:37.274127Z","steps":["trace[529912102] 'read index received' (duration: 433.641671ms)","trace[529912102] 'applied index is now lower than readState.Index' (duration: 32.727307ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:37.274344Z","caller":"traceutil/trace.go:171","msg":"trace[97455217] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"684.09678ms","start":"2026-06-04T12:48:36.590175Z","end":"2026-06-04T12:48:37.274272Z","steps":["trace[97455217] 'process raft request' (duration: 683.861644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.274444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.066494ms","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-06-04T12:48:37.274508Z","caller":"traceutil/trace.go:171","msg":"trace[385416017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25222; }","duration":"526.175557ms","start":"2026-06-04T12:48:36.748316Z","end":"2026-06-04T12:48:37.274492Z","steps":["trace[385416017] 'agreement among raft nodes before linearized reading' (duration: 525.966682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.274567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.748296Z","time spent":"526.256339ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:48:37.274607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.590156Z","time spent":"684.292535ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:37.274984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.215342ms","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:6"} {"level":"info","ts":"2026-06-04T12:48:37.274997Z","caller":"traceutil/trace.go:171","msg":"trace[476397352] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"687.565515ms","start":"2026-06-04T12:48:36.587414Z","end":"2026-06-04T12:48:37.27498Z","steps":["trace[476397352] 'process raft request' (duration: 654.003357ms)","trace[476397352] 'compare' (duration: 32.485241ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:37.275051Z","caller":"traceutil/trace.go:171","msg":"trace[1371541263] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25222; }","duration":"811.305664ms","start":"2026-06-04T12:48:36.463715Z","end":"2026-06-04T12:48:37.275021Z","steps":["trace[1371541263] 'agreement among raft nodes before linearized reading' (duration: 811.170461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.275081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.58739Z","time spent":"687.642446ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:37.275093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.463692Z","time spent":"811.389655ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:48:37.275354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.827306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-04T12:48:37.275415Z","caller":"traceutil/trace.go:171","msg":"trace[1063974481] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25222; }","duration":"897.891228ms","start":"2026-06-04T12:48:36.377512Z","end":"2026-06-04T12:48:37.275403Z","steps":["trace[1063974481] 'agreement among raft nodes before linearized reading' (duration: 897.603431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.275453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.377457Z","time spent":"897.985961ms","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":232,"response size":32,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:48:37.276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.51788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:37.276054Z","caller":"traceutil/trace.go:171","msg":"trace[1083493491] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25222; }","duration":"139.610071ms","start":"2026-06-04T12:48:37.13643Z","end":"2026-06-04T12:48:37.27604Z","steps":["trace[1083493491] 'agreement among raft nodes before linearized reading' (duration: 139.526049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.276032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.718292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:48:37.276035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.025136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:37.2761Z","caller":"traceutil/trace.go:171","msg":"trace[421847426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25222; }","duration":"465.831825ms","start":"2026-06-04T12:48:36.81025Z","end":"2026-06-04T12:48:37.276082Z","steps":["trace[421847426] 'agreement among raft nodes before linearized reading' (duration: 465.710712ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:37.276112Z","caller":"traceutil/trace.go:171","msg":"trace[1114299299] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25222; }","duration":"183.152479ms","start":"2026-06-04T12:48:37.092941Z","end":"2026-06-04T12:48:37.276093Z","steps":["trace[1114299299] 'agreement among raft nodes before linearized reading' (duration: 183.018086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.276135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.810233Z","time spent":"465.896067ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:48:37.276135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.436951ms","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-06-04T12:48:37.276187Z","caller":"traceutil/trace.go:171","msg":"trace[1683001359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25222; }","duration":"378.497662ms","start":"2026-06-04T12:48:36.897676Z","end":"2026-06-04T12:48:37.276174Z","steps":["trace[1683001359] 'agreement among raft nodes before linearized reading' (duration: 378.288557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.276222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:36.897611Z","time spent":"378.602015ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-04T12:48:37.832557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.336482ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-06-04T12:48:37.832674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.277403Z","time spent":"555.265589ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-04T12:48:37.9136Z","caller":"traceutil/trace.go:171","msg":"trace[2134585219] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"631.597617ms","start":"2026-06-04T12:48:37.281977Z","end":"2026-06-04T12:48:37.913574Z","steps":["trace[2134585219] 'process raft request' (duration: 631.504795ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:37.913645Z","caller":"traceutil/trace.go:171","msg":"trace[200467877] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"633.657307ms","start":"2026-06-04T12:48:37.279949Z","end":"2026-06-04T12:48:37.913606Z","steps":["trace[200467877] 'process raft request' (duration: 633.335958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.913809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.279927Z","time spent":"633.792731ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:37.913793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.281964Z","time spent":"631.722101ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:37.913975Z","caller":"traceutil/trace.go:171","msg":"trace[285450891] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"630.234184ms","start":"2026-06-04T12:48:37.283695Z","end":"2026-06-04T12:48:37.91393Z","steps":["trace[285450891] 'process raft request' (duration: 629.838194ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:37.914119Z","caller":"traceutil/trace.go:171","msg":"trace[1655864365] linearizableReadLoop","detail":"{readStateIndex:26063; appliedIndex:26058; }","duration":"553.740173ms","start":"2026-06-04T12:48:37.360363Z","end":"2026-06-04T12:48:37.914103Z","steps":["trace[1655864365] 'read index received' (duration: 193.824867ms)","trace[1655864365] 'applied index is now lower than readState.Index' (duration: 359.914386ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:37.914199Z","caller":"traceutil/trace.go:171","msg":"trace[241356375] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"367.19343ms","start":"2026-06-04T12:48:37.546983Z","end":"2026-06-04T12:48:37.914176Z","steps":["trace[241356375] 'process raft request' (duration: 367.061707ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:37.91419Z","caller":"traceutil/trace.go:171","msg":"trace[1655074151] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"630.323216ms","start":"2026-06-04T12:48:37.283833Z","end":"2026-06-04T12:48:37.914156Z","steps":["trace[1655074151] 'process raft request' (duration: 630.026359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.914307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.546956Z","time spent":"367.290123ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:37.914327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.283677Z","time spent":"630.411288ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:37.914349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.283825Z","time spent":"630.438718ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:37.914389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.020729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:37.914424Z","caller":"traceutil/trace.go:171","msg":"trace[1902971381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25227; }","duration":"554.085681ms","start":"2026-06-04T12:48:37.360328Z","end":"2026-06-04T12:48:37.914414Z","steps":["trace[1902971381] 'agreement among raft nodes before linearized reading' (duration: 553.872726ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:37.914448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.36031Z","time spent":"554.132792ms","remote":"127.0.0.1:50246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:48:38.376598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.120167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:38.377598Z","caller":"traceutil/trace.go:171","msg":"trace[1540738468] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"141.877515ms","start":"2026-06-04T12:48:38.2357Z","end":"2026-06-04T12:48:38.377577Z","steps":["trace[1540738468] 'process raft request' (duration: 141.770622ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:38.377604Z","caller":"traceutil/trace.go:171","msg":"trace[2094928512] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"543.277961ms","start":"2026-06-04T12:48:37.83425Z","end":"2026-06-04T12:48:38.377528Z","steps":["trace[2094928512] 'process raft request' (duration: 254.16675ms)","trace[2094928512] 'compare' (duration: 288.009535ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:38.377865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.834226Z","time spent":"543.502935ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:38.378004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.172819ms","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-06-04T12:48:38.378084Z","caller":"traceutil/trace.go:171","msg":"trace[855285580] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25229; }","duration":"708.305572ms","start":"2026-06-04T12:48:37.669752Z","end":"2026-06-04T12:48:38.378057Z","steps":["trace[855285580] 'agreement among raft nodes before linearized reading' (duration: 708.19636ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:38.377641Z","caller":"traceutil/trace.go:171","msg":"trace[1282532626] linearizableReadLoop","detail":"{readStateIndex:26065; appliedIndex:26064; }","duration":"463.460798ms","start":"2026-06-04T12:48:37.914158Z","end":"2026-06-04T12:48:38.377619Z","steps":["trace[1282532626] 'read index received' (duration: 174.219344ms)","trace[1282532626] 'applied index is now lower than readState.Index' (duration: 289.238384ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:38.378155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.035358ms","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-06-04T12:48:38.378015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.682021ms","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-06-04T12:48:38.378212Z","caller":"traceutil/trace.go:171","msg":"trace[1868730413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25229; }","duration":"818.955877ms","start":"2026-06-04T12:48:37.559243Z","end":"2026-06-04T12:48:38.378199Z","steps":["trace[1868730413] 'agreement among raft nodes before linearized reading' (duration: 818.433254ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:38.378259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.559229Z","time spent":"819.016799ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-04T12:48:38.378159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.669719Z","time spent":"708.422245ms","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"info","ts":"2026-06-04T12:48:38.378211Z","caller":"traceutil/trace.go:171","msg":"trace[599557334] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25229; }","duration":"814.10997ms","start":"2026-06-04T12:48:37.564085Z","end":"2026-06-04T12:48:38.378195Z","steps":["trace[599557334] 'agreement among raft nodes before linearized reading' (duration: 813.979957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:38.3784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.56407Z","time spent":"814.317126ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-04T12:48:38.492346Z","caller":"traceutil/trace.go:171","msg":"trace[1044900567] linearizableReadLoop","detail":"{readStateIndex:26067; appliedIndex:26066; }","duration":"114.125048ms","start":"2026-06-04T12:48:38.37819Z","end":"2026-06-04T12:48:38.492315Z","steps":["trace[1044900567] 'read index received' (duration: 106.33028ms)","trace[1044900567] 'applied index is now lower than readState.Index' (duration: 7.793768ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:38.492397Z","caller":"traceutil/trace.go:171","msg":"trace[1758901420] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"191.696835ms","start":"2026-06-04T12:48:38.300647Z","end":"2026-06-04T12:48:38.492344Z","steps":["trace[1758901420] 'process raft request' (duration: 183.902677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:38.492659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.192666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:48:38.49279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.724948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-06-04T12:48:38.492788Z","caller":"traceutil/trace.go:171","msg":"trace[1080555138] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"108.752558ms","start":"2026-06-04T12:48:38.384002Z","end":"2026-06-04T12:48:38.492755Z","steps":["trace[1080555138] 'process raft request' (duration: 108.266086ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:38.492879Z","caller":"traceutil/trace.go:171","msg":"trace[1695566897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25231; }","duration":"577.415041ms","start":"2026-06-04T12:48:37.915376Z","end":"2026-06-04T12:48:38.492791Z","steps":["trace[1695566897] 'agreement among raft nodes before linearized reading' (duration: 577.040733ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:38.492896Z","caller":"traceutil/trace.go:171","msg":"trace[50695182] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"108.435549ms","start":"2026-06-04T12:48:38.384439Z","end":"2026-06-04T12:48:38.492875Z","steps":["trace[50695182] 'process raft request' (duration: 108.104142ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:38.492838Z","caller":"traceutil/trace.go:171","msg":"trace[930529737] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25231; }","duration":"520.84705ms","start":"2026-06-04T12:48:37.971975Z","end":"2026-06-04T12:48:38.492822Z","steps":["trace[930529737] 'agreement among raft nodes before linearized reading' (duration: 520.343658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:38.492967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.915357Z","time spent":"577.574886ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-04T12:48:38.492994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:37.971942Z","time spent":"521.037095ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1885,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-06-04T12:48:38.492982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.754804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-04T12:48:38.493072Z","caller":"traceutil/trace.go:171","msg":"trace[1626001823] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25232; }","duration":"112.857307ms","start":"2026-06-04T12:48:38.380174Z","end":"2026-06-04T12:48:38.493031Z","steps":["trace[1626001823] 'agreement among raft nodes before linearized reading' (duration: 112.610461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:42.667829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.427248ms","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-06-04T12:48:42.667914Z","caller":"traceutil/trace.go:171","msg":"trace[1871301515] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:25318; }","duration":"166.569781ms","start":"2026-06-04T12:48:42.501326Z","end":"2026-06-04T12:48:42.667896Z","steps":["trace[1871301515] 'count revisions from in-memory index tree' (duration: 166.339435ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:42.668107Z","caller":"traceutil/trace.go:171","msg":"trace[659996367] linearizableReadLoop","detail":"{readStateIndex:26157; appliedIndex:26156; }","duration":"161.320805ms","start":"2026-06-04T12:48:42.506771Z","end":"2026-06-04T12:48:42.668092Z","steps":["trace[659996367] 'read index received' (duration: 66.804029ms)","trace[659996367] 'applied index is now lower than readState.Index' (duration: 94.516086ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:42.668149Z","caller":"traceutil/trace.go:171","msg":"trace[840380613] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"161.529849ms","start":"2026-06-04T12:48:42.506589Z","end":"2026-06-04T12:48:42.668119Z","steps":["trace[840380613] 'process raft request' (duration: 67.000664ms)","trace[840380613] 'compare' (duration: 94.347591ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:42.668292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.119369ms","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-06-04T12:48:42.668339Z","caller":"traceutil/trace.go:171","msg":"trace[1996765391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25319; }","duration":"161.16305ms","start":"2026-06-04T12:48:42.507161Z","end":"2026-06-04T12:48:42.668324Z","steps":["trace[1996765391] 'agreement among raft nodes before linearized reading' (duration: 161.038787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:42.668293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.515559ms","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-06-04T12:48:42.668364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.928888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fba1778b-c071-48cc-b557-a5a370fc545a\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-04T12:48:42.668434Z","caller":"traceutil/trace.go:171","msg":"trace[577386408] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fba1778b-c071-48cc-b557-a5a370fc545a; range_end:; response_count:1; response_revision:25319; }","duration":"152.02957ms","start":"2026-06-04T12:48:42.516386Z","end":"2026-06-04T12:48:42.668415Z","steps":["trace[577386408] 'agreement among raft nodes before linearized reading' (duration: 151.839155ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:42.668448Z","caller":"traceutil/trace.go:171","msg":"trace[336708214] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25319; }","duration":"161.688683ms","start":"2026-06-04T12:48:42.506706Z","end":"2026-06-04T12:48:42.668394Z","steps":["trace[336708214] 'agreement among raft nodes before linearized reading' (duration: 161.432487ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:42.805759Z","caller":"traceutil/trace.go:171","msg":"trace[2057013791] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"132.874059ms","start":"2026-06-04T12:48:42.67286Z","end":"2026-06-04T12:48:42.805734Z","steps":["trace[2057013791] 'process raft request' (duration: 132.230813ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:42.861875Z","caller":"traceutil/trace.go:171","msg":"trace[654323815] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"188.57332ms","start":"2026-06-04T12:48:42.673258Z","end":"2026-06-04T12:48:42.861831Z","steps":["trace[654323815] 'process raft request' (duration: 188.373316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:43.138906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.821455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:43.139194Z","caller":"traceutil/trace.go:171","msg":"trace[406238148] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"311.320246ms","start":"2026-06-04T12:48:42.827822Z","end":"2026-06-04T12:48:43.139142Z","steps":["trace[406238148] 'process raft request' (duration: 105.151362ms)","trace[406238148] 'compare' (duration: 205.523388ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:43.139217Z","caller":"traceutil/trace.go:171","msg":"trace[1700213464] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"307.163236ms","start":"2026-06-04T12:48:42.83203Z","end":"2026-06-04T12:48:43.139193Z","steps":["trace[1700213464] 'process raft request' (duration: 307.081804ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:43.139224Z","caller":"traceutil/trace.go:171","msg":"trace[139228307] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"310.445654ms","start":"2026-06-04T12:48:42.828751Z","end":"2026-06-04T12:48:43.139196Z","steps":["trace[139228307] 'process raft request' (duration: 310.25953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:43.139336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:42.832019Z","time spent":"307.263468ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:43.139349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:42.827794Z","time spent":"311.474319ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:43.13972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:42.828742Z","time spent":"310.550097ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:43.255385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.164867ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-04T12:48:45.672595Z","caller":"traceutil/trace.go:171","msg":"trace[480766000] linearizableReadLoop","detail":"{readStateIndex:26186; appliedIndex:26184; }","duration":"304.84679ms","start":"2026-06-04T12:48:45.367722Z","end":"2026-06-04T12:48:45.672569Z","steps":["trace[480766000] 'read index received' (duration: 49.872641ms)","trace[480766000] 'applied index is now lower than readState.Index' (duration: 254.972809ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:45.672842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.097076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-06-04T12:48:45.672885Z","caller":"traceutil/trace.go:171","msg":"trace[554508788] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25347; }","duration":"305.198058ms","start":"2026-06-04T12:48:45.367675Z","end":"2026-06-04T12:48:45.672873Z","steps":["trace[554508788] 'agreement among raft nodes before linearized reading' (duration: 304.991514ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:45.672846Z","caller":"traceutil/trace.go:171","msg":"trace[1420578375] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"307.920093ms","start":"2026-06-04T12:48:45.364866Z","end":"2026-06-04T12:48:45.672786Z","steps":["trace[1420578375] 'process raft request' (duration: 307.519604ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:45.672926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:45.367666Z","time spent":"305.251679ms","remote":"127.0.0.1:50430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-04T12:48:45.673107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:45.364831Z","time spent":"308.117868ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:48.998007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.482256ms","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-06-04T12:48:48.99808Z","caller":"traceutil/trace.go:171","msg":"trace[110911287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25387; }","duration":"168.618129ms","start":"2026-06-04T12:48:48.829448Z","end":"2026-06-04T12:48:48.998066Z","steps":["trace[110911287] 'range keys from in-memory index tree' (duration: 168.310512ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:49.187207Z","caller":"traceutil/trace.go:171","msg":"trace[492892630] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"184.960843ms","start":"2026-06-04T12:48:49.002216Z","end":"2026-06-04T12:48:49.187177Z","steps":["trace[492892630] 'process raft request' (duration: 184.774599ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:49.342984Z","caller":"traceutil/trace.go:171","msg":"trace[1809238280] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"338.893369ms","start":"2026-06-04T12:48:49.004041Z","end":"2026-06-04T12:48:49.342935Z","steps":["trace[1809238280] 'process raft request' (duration: 322.975856ms)","trace[1809238280] 'compare' (duration: 15.621695ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:49.343191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:49.004012Z","time spent":"339.068884ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:49.344359Z","caller":"traceutil/trace.go:171","msg":"trace[1261292131] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"186.624204ms","start":"2026-06-04T12:48:49.157708Z","end":"2026-06-04T12:48:49.344332Z","steps":["trace[1261292131] 'process raft request' (duration: 185.168418ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:49.344573Z","caller":"traceutil/trace.go:171","msg":"trace[1858756222] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"186.753816ms","start":"2026-06-04T12:48:49.157812Z","end":"2026-06-04T12:48:49.344566Z","steps":["trace[1858756222] 'process raft request' (duration: 186.466619ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:49.344739Z","caller":"traceutil/trace.go:171","msg":"trace[248686174] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26228; }","duration":"189.538354ms","start":"2026-06-04T12:48:49.155175Z","end":"2026-06-04T12:48:49.344713Z","steps":["trace[248686174] 'read index received' (duration: 31.790236ms)","trace[248686174] 'applied index is now lower than readState.Index' (duration: 157.746678ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:49.344785Z","caller":"traceutil/trace.go:171","msg":"trace[1564892136] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"186.038979ms","start":"2026-06-04T12:48:49.158736Z","end":"2026-06-04T12:48:49.344775Z","steps":["trace[1564892136] 'process raft request' (duration: 185.803714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:49.34487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.667597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:48:49.344902Z","caller":"traceutil/trace.go:171","msg":"trace[1880299721] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25392; }","duration":"189.760919ms","start":"2026-06-04T12:48:49.15513Z","end":"2026-06-04T12:48:49.344891Z","steps":["trace[1880299721] 'agreement among raft nodes before linearized reading' (duration: 189.679997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:54.320379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.851367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:48:54.321307Z","caller":"traceutil/trace.go:171","msg":"trace[2042188425] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"288.353443ms","start":"2026-06-04T12:48:54.032131Z","end":"2026-06-04T12:48:54.320485Z","steps":["trace[2042188425] 'process raft request' (duration: 94.302441ms)","trace[2042188425] 'compare' (duration: 193.633432ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:48:54.398461Z","caller":"traceutil/trace.go:171","msg":"trace[1492224890] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"365.603272ms","start":"2026-06-04T12:48:54.032821Z","end":"2026-06-04T12:48:54.398425Z","steps":["trace[1492224890] 'process raft request' (duration: 365.242914ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:54.398676Z","caller":"traceutil/trace.go:171","msg":"trace[262421659] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"365.10077ms","start":"2026-06-04T12:48:54.033547Z","end":"2026-06-04T12:48:54.398648Z","steps":["trace[262421659] 'process raft request' (duration: 364.827893ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:54.398745Z","caller":"traceutil/trace.go:171","msg":"trace[2046107758] linearizableReadLoop","detail":"{readStateIndex:26279; appliedIndex:26274; }","duration":"325.383403ms","start":"2026-06-04T12:48:54.073341Z","end":"2026-06-04T12:48:54.398724Z","steps":["trace[2046107758] 'read index received' (duration: 53.105188ms)","trace[2046107758] 'applied index is now lower than readState.Index' (duration: 272.277105ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:48:54.398824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:54.033535Z","time spent":"365.208183ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:54.398894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:54.032776Z","time spent":"365.783567ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:54.398885Z","caller":"traceutil/trace.go:171","msg":"trace[463837075] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"363.129612ms","start":"2026-06-04T12:48:54.035712Z","end":"2026-06-04T12:48:54.398842Z","steps":["trace[463837075] 'process raft request' (duration: 362.852876ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:48:54.398925Z","caller":"traceutil/trace.go:171","msg":"trace[1571849584] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"332.439163ms","start":"2026-06-04T12:48:54.066438Z","end":"2026-06-04T12:48:54.398877Z","steps":["trace[1571849584] 'process raft request' (duration: 332.238768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:54.399018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.668381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-06-04T12:48:54.399062Z","caller":"traceutil/trace.go:171","msg":"trace[933836744] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:25437; }","duration":"325.787943ms","start":"2026-06-04T12:48:54.073262Z","end":"2026-06-04T12:48:54.39905Z","steps":["trace[933836744] 'agreement among raft nodes before linearized reading' (duration: 325.611979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:48:54.399068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:54.035701Z","time spent":"363.268395ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:48:54.399094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:54.073248Z","time spent":"325.838505ms","remote":"127.0.0.1:35000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7937,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"warn","ts":"2026-06-04T12:48:54.399097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:48:54.066402Z","time spent":"332.602297ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:48:57.224528Z","caller":"traceutil/trace.go:171","msg":"trace[672238707] transaction","detail":"{read_only:false; response_revision:25457; number_of_response:1; }","duration":"158.111886ms","start":"2026-06-04T12:48:57.066389Z","end":"2026-06-04T12:48:57.224501Z","steps":["trace[672238707] 'process raft request' (duration: 157.934792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:02.758523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.030747ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-04T12:49:02.758695Z","caller":"traceutil/trace.go:171","msg":"trace[1909210335] linearizableReadLoop","detail":"{readStateIndex:26342; appliedIndex:26341; }","duration":"324.635585ms","start":"2026-06-04T12:49:02.434032Z","end":"2026-06-04T12:49:02.758667Z","steps":["trace[1909210335] 'read index received' (duration: 89.424594ms)","trace[1909210335] 'applied index is now lower than readState.Index' (duration: 235.208831ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:49:02.758916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.860972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-04T12:49:02.758971Z","caller":"traceutil/trace.go:171","msg":"trace[1399561891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25498; }","duration":"324.964814ms","start":"2026-06-04T12:49:02.433993Z","end":"2026-06-04T12:49:02.758958Z","steps":["trace[1399561891] 'agreement among raft nodes before linearized reading' (duration: 324.78899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:02.758998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:02.433977Z","time spent":"325.014755ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-04T12:49:02.759019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.203742ms","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-06-04T12:49:02.759072Z","caller":"traceutil/trace.go:171","msg":"trace[122809985] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25498; }","duration":"323.275744ms","start":"2026-06-04T12:49:02.435777Z","end":"2026-06-04T12:49:02.759053Z","steps":["trace[122809985] 'agreement among raft nodes before linearized reading' (duration: 323.074909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:02.759131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:02.435768Z","time spent":"323.345645ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-04T12:49:02.759145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.88628ms","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-06-04T12:49:02.759254Z","caller":"traceutil/trace.go:171","msg":"trace[1289445472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25498; }","duration":"322.087135ms","start":"2026-06-04T12:49:02.437146Z","end":"2026-06-04T12:49:02.759233Z","steps":["trace[1289445472] 'agreement among raft nodes before linearized reading' (duration: 321.766377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:02.759321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:02.437119Z","time spent":"322.192387ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-04T12:49:04.148513Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22097} {"level":"info","ts":"2026-06-04T12:49:04.356371Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22097,"took":"198.884558ms","hash":109263158,"current-db-size-bytes":60219392,"current-db-size":"60 MB","current-db-size-in-use-bytes":42774528,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-06-04T12:49:04.356481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":109263158,"revision":22097,"compact-revision":19909} {"level":"info","ts":"2026-06-04T12:49:05.755347Z","caller":"traceutil/trace.go:171","msg":"trace[1631565597] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"154.326085ms","start":"2026-06-04T12:49:05.60099Z","end":"2026-06-04T12:49:05.755316Z","steps":["trace[1631565597] 'process raft request' (duration: 154.12203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:07.901302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.4138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:49:07.901442Z","caller":"traceutil/trace.go:171","msg":"trace[1711601915] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25541; }","duration":"233.646115ms","start":"2026-06-04T12:49:07.667764Z","end":"2026-06-04T12:49:07.90141Z","steps":["trace[1711601915] 'count revisions from in-memory index tree' (duration: 233.128523ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:07.901491Z","caller":"traceutil/trace.go:171","msg":"trace[1009058430] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"134.98541ms","start":"2026-06-04T12:49:07.766448Z","end":"2026-06-04T12:49:07.901434Z","steps":["trace[1009058430] 'process raft request' (duration: 134.304144ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:11.584535Z","caller":"traceutil/trace.go:171","msg":"trace[968536582] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"151.171389ms","start":"2026-06-04T12:49:11.433328Z","end":"2026-06-04T12:49:11.584499Z","steps":["trace[968536582] 'process raft request' (duration: 75.448286ms)","trace[968536582] 'compare' (duration: 75.255222ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:11.584684Z","caller":"traceutil/trace.go:171","msg":"trace[2044606910] linearizableReadLoop","detail":"{readStateIndex:26424; appliedIndex:26421; }","duration":"133.071373ms","start":"2026-06-04T12:49:11.451584Z","end":"2026-06-04T12:49:11.584656Z","steps":["trace[2044606910] 'read index received' (duration: 31.790835ms)","trace[2044606910] 'applied index is now lower than readState.Index' (duration: 101.279478ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:11.584852Z","caller":"traceutil/trace.go:171","msg":"trace[35537068] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"143.423962ms","start":"2026-06-04T12:49:11.441416Z","end":"2026-06-04T12:49:11.58484Z","steps":["trace[35537068] 'process raft request' (duration: 143.036973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:11.585058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.453142ms","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-06-04T12:49:11.585145Z","caller":"traceutil/trace.go:171","msg":"trace[1468108253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25578; }","duration":"133.587065ms","start":"2026-06-04T12:49:11.451534Z","end":"2026-06-04T12:49:11.585121Z","steps":["trace[1468108253] 'agreement among raft nodes before linearized reading' (duration: 133.304548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:13.676229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.506102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-04T12:49:13.676328Z","caller":"traceutil/trace.go:171","msg":"trace[1536714640] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"182.936296ms","start":"2026-06-04T12:49:13.493373Z","end":"2026-06-04T12:49:13.676309Z","steps":["trace[1536714640] 'process raft request' (duration: 52.264429ms)","trace[1536714640] 'compare' (duration: 130.39571ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:19.619327Z","caller":"traceutil/trace.go:171","msg":"trace[1245740098] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"107.614561ms","start":"2026-06-04T12:49:19.51167Z","end":"2026-06-04T12:49:19.619285Z","steps":["trace[1245740098] 'process raft request' (duration: 107.223622ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:32.869597Z","caller":"traceutil/trace.go:171","msg":"trace[975983570] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"169.141682ms","start":"2026-06-04T12:49:32.70042Z","end":"2026-06-04T12:49:32.869562Z","steps":["trace[975983570] 'process raft request' (duration: 168.853595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:33.034658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.437894ms","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-06-04T12:49:33.034763Z","caller":"traceutil/trace.go:171","msg":"trace[308599043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25736; }","duration":"104.599288ms","start":"2026-06-04T12:49:32.930123Z","end":"2026-06-04T12:49:33.034723Z","steps":["trace[308599043] 'range keys from in-memory index tree' (duration: 104.231289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:33.035136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.072118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-06-04T12:49:33.035194Z","caller":"traceutil/trace.go:171","msg":"trace[1009246748] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25736; }","duration":"102.15979ms","start":"2026-06-04T12:49:32.933012Z","end":"2026-06-04T12:49:33.035172Z","steps":["trace[1009246748] 'range keys from in-memory index tree' (duration: 101.285769ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:33.328726Z","caller":"traceutil/trace.go:171","msg":"trace[1745544727] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"100.08745ms","start":"2026-06-04T12:49:33.228609Z","end":"2026-06-04T12:49:33.328697Z","steps":["trace[1745544727] 'process raft request' (duration: 99.913546ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:33.546951Z","caller":"traceutil/trace.go:171","msg":"trace[1533485644] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"211.904942ms","start":"2026-06-04T12:49:33.334971Z","end":"2026-06-04T12:49:33.546876Z","steps":["trace[1533485644] 'process raft request' (duration: 178.19656ms)","trace[1533485644] 'compare' (duration: 33.251671ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:33.616234Z","caller":"traceutil/trace.go:171","msg":"trace[1626149290] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26592; }","duration":"242.372185ms","start":"2026-06-04T12:49:33.373794Z","end":"2026-06-04T12:49:33.616166Z","steps":["trace[1626149290] 'read index received' (duration: 139.468868ms)","trace[1626149290] 'applied index is now lower than readState.Index' (duration: 102.902107ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:33.616378Z","caller":"traceutil/trace.go:171","msg":"trace[2054167846] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"279.006068ms","start":"2026-06-04T12:49:33.337341Z","end":"2026-06-04T12:49:33.616347Z","steps":["trace[2054167846] 'process raft request' (duration: 278.728561ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:33.616367Z","caller":"traceutil/trace.go:171","msg":"trace[308551791] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"281.129119ms","start":"2026-06-04T12:49:33.335183Z","end":"2026-06-04T12:49:33.616312Z","steps":["trace[308551791] 'process raft request' (duration: 280.716469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:33.616403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.591901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:49:33.616738Z","caller":"traceutil/trace.go:171","msg":"trace[1608960357] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25744; }","duration":"242.96745ms","start":"2026-06-04T12:49:33.373728Z","end":"2026-06-04T12:49:33.616695Z","steps":["trace[1608960357] 'agreement among raft nodes before linearized reading' (duration: 242.597311ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:38.022747Z","caller":"traceutil/trace.go:171","msg":"trace[358907199] linearizableReadLoop","detail":"{readStateIndex:26631; appliedIndex:26627; }","duration":"164.444509ms","start":"2026-06-04T12:49:37.858279Z","end":"2026-06-04T12:49:38.022724Z","steps":["trace[358907199] 'read index received' (duration: 55.554487ms)","trace[358907199] 'applied index is now lower than readState.Index' (duration: 108.889082ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:38.02278Z","caller":"traceutil/trace.go:171","msg":"trace[1445312711] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"171.309346ms","start":"2026-06-04T12:49:37.851434Z","end":"2026-06-04T12:49:38.022743Z","steps":["trace[1445312711] 'process raft request' (duration: 158.608639ms)","trace[1445312711] 'compare' (duration: 12.43617ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:38.022806Z","caller":"traceutil/trace.go:171","msg":"trace[1783449697] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"171.235643ms","start":"2026-06-04T12:49:37.851537Z","end":"2026-06-04T12:49:38.022772Z","steps":["trace[1783449697] 'process raft request' (duration: 171.066729ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:38.022873Z","caller":"traceutil/trace.go:171","msg":"trace[1135638798] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"171.105699ms","start":"2026-06-04T12:49:37.851757Z","end":"2026-06-04T12:49:38.022862Z","steps":["trace[1135638798] 'process raft request' (duration: 170.923275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:38.023351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.057054ms","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-06-04T12:49:38.023399Z","caller":"traceutil/trace.go:171","msg":"trace[2118078268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25779; }","duration":"165.150386ms","start":"2026-06-04T12:49:37.858238Z","end":"2026-06-04T12:49:38.023388Z","steps":["trace[2118078268] 'agreement among raft nodes before linearized reading' (duration: 164.757747ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:43.286708Z","caller":"traceutil/trace.go:171","msg":"trace[688030021] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"120.384728ms","start":"2026-06-04T12:49:43.166171Z","end":"2026-06-04T12:49:43.286555Z","steps":["trace[688030021] 'process raft request' (duration: 120.148523ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:43.287654Z","caller":"traceutil/trace.go:171","msg":"trace[42431792] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"120.066141ms","start":"2026-06-04T12:49:43.167566Z","end":"2026-06-04T12:49:43.287632Z","steps":["trace[42431792] 'process raft request' (duration: 119.628691ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:43.287804Z","caller":"traceutil/trace.go:171","msg":"trace[2005471271] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"119.59584ms","start":"2026-06-04T12:49:43.168188Z","end":"2026-06-04T12:49:43.287783Z","steps":["trace[2005471271] 'process raft request' (duration: 119.17667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:43.463921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.853662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:49:43.46399Z","caller":"traceutil/trace.go:171","msg":"trace[1371435777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25817; }","duration":"101.977085ms","start":"2026-06-04T12:49:43.361998Z","end":"2026-06-04T12:49:43.463975Z","steps":["trace[1371435777] 'range keys from in-memory index tree' (duration: 101.734669ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:44.014099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.857472ms","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-06-04T12:49:44.014183Z","caller":"traceutil/trace.go:171","msg":"trace[1662563115] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25818; }","duration":"221.980855ms","start":"2026-06-04T12:49:43.79218Z","end":"2026-06-04T12:49:44.014161Z","steps":["trace[1662563115] 'range keys from in-memory index tree' (duration: 221.655196ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:44.306984Z","caller":"traceutil/trace.go:171","msg":"trace[1115880934] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"287.902742ms","start":"2026-06-04T12:49:44.019062Z","end":"2026-06-04T12:49:44.306965Z","steps":["trace[1115880934] 'process raft request' (duration: 287.773159ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:44.307033Z","caller":"traceutil/trace.go:171","msg":"trace[457210725] linearizableReadLoop","detail":"{readStateIndex:26673; appliedIndex:26673; }","duration":"284.206283ms","start":"2026-06-04T12:49:44.02278Z","end":"2026-06-04T12:49:44.306987Z","steps":["trace[457210725] 'read index received' (duration: 284.188893ms)","trace[457210725] 'applied index is now lower than readState.Index' (duration: 14.03µs)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:49:44.3073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.50635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7dbeb39a-d597-481c-979d-5bf77e87702e\" ","response":"range_response_count:1 size:1294"} {"level":"info","ts":"2026-06-04T12:49:44.307373Z","caller":"traceutil/trace.go:171","msg":"trace[1282087464] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7dbeb39a-d597-481c-979d-5bf77e87702e; range_end:; response_count:1; response_revision:25820; }","duration":"284.603692ms","start":"2026-06-04T12:49:44.022749Z","end":"2026-06-04T12:49:44.307353Z","steps":["trace[1282087464] 'agreement among raft nodes before linearized reading' (duration: 284.386347ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:44.462977Z","caller":"traceutil/trace.go:171","msg":"trace[632804173] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"421.483738ms","start":"2026-06-04T12:49:44.041449Z","end":"2026-06-04T12:49:44.462933Z","steps":["trace[632804173] 'process raft request' (duration: 385.669716ms)","trace[632804173] 'compare' (duration: 35.480783ms)"],"step_count":2} {"level":"warn","ts":"2026-06-04T12:49:44.463233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:44.041417Z","time spent":"421.676583ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-04T12:49:44.463306Z","caller":"traceutil/trace.go:171","msg":"trace[1606199800] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"413.634788ms","start":"2026-06-04T12:49:44.049643Z","end":"2026-06-04T12:49:44.463278Z","steps":["trace[1606199800] 'process raft request' (duration: 413.455464ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:44.463395Z","caller":"traceutil/trace.go:171","msg":"trace[1188416140] linearizableReadLoop","detail":"{readStateIndex:26677; appliedIndex:26673; }","duration":"156.227762ms","start":"2026-06-04T12:49:44.307142Z","end":"2026-06-04T12:49:44.463369Z","steps":["trace[1188416140] 'read index received' (duration: 119.996469ms)","trace[1188416140] 'applied index is now lower than readState.Index' (duration: 36.230273ms)"],"step_count":2} {"level":"info","ts":"2026-06-04T12:49:44.463455Z","caller":"traceutil/trace.go:171","msg":"trace[1377131163] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"413.021474ms","start":"2026-06-04T12:49:44.050415Z","end":"2026-06-04T12:49:44.463436Z","steps":["trace[1377131163] 'process raft request' (duration: 412.813009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:44.463498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:44.049625Z","time spent":"413.770362ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:49:44.463538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:44.050386Z","time spent":"413.111777ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:49:44.463572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.669945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:49:44.46362Z","caller":"traceutil/trace.go:171","msg":"trace[2035092993] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25824; }","duration":"159.737266ms","start":"2026-06-04T12:49:44.303869Z","end":"2026-06-04T12:49:44.463606Z","steps":["trace[2035092993] 'agreement among raft nodes before linearized reading' (duration: 159.621433ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:44.463322Z","caller":"traceutil/trace.go:171","msg":"trace[2135677445] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"417.457551ms","start":"2026-06-04T12:49:44.045803Z","end":"2026-06-04T12:49:44.463261Z","steps":["trace[2135677445] 'process raft request' (duration: 417.043381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:44.46369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:44.045785Z","time spent":"417.87577ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-04T12:49:44.463795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.159916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-04T12:49:44.463823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.279835ms","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-06-04T12:49:44.463834Z","caller":"traceutil/trace.go:171","msg":"trace[508396326] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25824; }","duration":"435.196037ms","start":"2026-06-04T12:49:44.028626Z","end":"2026-06-04T12:49:44.463822Z","steps":["trace[508396326] 'agreement among raft nodes before linearized reading' (duration: 435.134146ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:44.463866Z","caller":"traceutil/trace.go:171","msg":"trace[1173241141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25824; }","duration":"397.372077ms","start":"2026-06-04T12:49:44.066482Z","end":"2026-06-04T12:49:44.463854Z","steps":["trace[1173241141] 'agreement among raft nodes before linearized reading' (duration: 397.212263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:44.463872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:44.028602Z","time spent":"435.25951ms","remote":"127.0.0.1:56758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-06-04T12:49:44.463909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:44.066448Z","time spent":"397.453549ms","remote":"127.0.0.1:50522","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-06-04T12:49:44.464063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.009815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-06-04T12:49:44.464097Z","caller":"traceutil/trace.go:171","msg":"trace[586869838] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25824; }","duration":"374.074217ms","start":"2026-06-04T12:49:44.090013Z","end":"2026-06-04T12:49:44.464088Z","steps":["trace[586869838] 'agreement among raft nodes before linearized reading' (duration: 373.983134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:44.464126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-04T12:49:44.089997Z","time spent":"374.122068ms","remote":"127.0.0.1:50522","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-06-04T12:49:44.512394Z","caller":"traceutil/trace.go:171","msg":"trace[868246136] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"193.756375ms","start":"2026-06-04T12:49:44.318595Z","end":"2026-06-04T12:49:44.512352Z","steps":["trace[868246136] 'process raft request' (duration: 193.482428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:44.512752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.142835ms","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-06-04T12:49:44.512866Z","caller":"traceutil/trace.go:171","msg":"trace[661067488] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25825; }","duration":"111.305809ms","start":"2026-06-04T12:49:44.401534Z","end":"2026-06-04T12:49:44.512839Z","steps":["trace[661067488] 'agreement among raft nodes before linearized reading' (duration: 110.969941ms)"],"step_count":1} {"level":"warn","ts":"2026-06-04T12:49:44.513164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.341761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-04T12:49:44.513241Z","caller":"traceutil/trace.go:171","msg":"trace[871577911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25825; }","duration":"165.816652ms","start":"2026-06-04T12:49:44.347407Z","end":"2026-06-04T12:49:44.513223Z","steps":["trace[871577911] 'agreement among raft nodes before linearized reading' (duration: 165.250039ms)"],"step_count":1} {"level":"info","ts":"2026-06-04T12:49:59.860845Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000691f.wal"} {"level":"info","ts":"2026-06-04T12:54:04.154222Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25512} {"level":"info","ts":"2026-06-04T12:54:04.307288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25512,"took":"149.164264ms","hash":4291106618,"current-db-size-bytes":61906944,"current-db-size":"62 MB","current-db-size-in-use-bytes":35721216,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-04T12:54:04.307385Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4291106618,"revision":25512,"compact-revision":22097} {"level":"info","ts":"2026-06-04T12:55:29.846488Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T12:55:29.851955Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-04T12:55:29.852077Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-04T12:59:04.162073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28395} {"level":"info","ts":"2026-06-04T12:59:04.301572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28395,"took":"135.649543ms","hash":1293109404,"current-db-size-bytes":61906944,"current-db-size":"62 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-04T12:59:04.30165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1293109404,"revision":28395,"compact-revision":25512} {"level":"info","ts":"2026-06-04T13:04:04.169696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30620} {"level":"info","ts":"2026-06-04T13:04:04.286905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30620,"took":"111.624825ms","hash":926910873,"current-db-size-bytes":61906944,"current-db-size":"62 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-04T13:04:04.286994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":926910873,"revision":30620,"compact-revision":28395} {"level":"info","ts":"2026-06-04T13:09:04.179697Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32947} {"level":"info","ts":"2026-06-04T13:09:04.326157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32947,"took":"142.742643ms","hash":2003277580,"current-db-size-bytes":62947328,"current-db-size":"63 MB","current-db-size-in-use-bytes":31453184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T13:09:04.326226Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2003277580,"revision":32947,"compact-revision":30620} {"level":"info","ts":"2026-06-04T13:14:04.188149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35275} {"level":"info","ts":"2026-06-04T13:14:04.380949Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35275,"took":"184.367802ms","hash":4122788468,"current-db-size-bytes":62947328,"current-db-size":"63 MB","current-db-size-in-use-bytes":31952896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T13:14:04.381022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4122788468,"revision":35275,"compact-revision":32947} {"level":"info","ts":"2026-06-04T13:16:49.102922Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-04T13:16:49.108456Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-04T13:16:49.108742Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-04T13:19:04.200048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37503} {"level":"info","ts":"2026-06-04T13:19:04.329615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37503,"took":"125.736481ms","hash":3644097783,"current-db-size-bytes":62947328,"current-db-size":"63 MB","current-db-size-in-use-bytes":31768576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-04T13:19:04.329698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3644097783,"revision":37503,"compact-revision":35275}