{"level":"warn","ts":"2026-07-30T00:54:47.109952Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T00:54:47.110104Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.222:2380","--initial-cluster=instance=https://162.253.55.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.222: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-07-30T00:54:47.110199Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T00:54:47.110213Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-07-30T00:54:47.110305Z","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-07-30T00:54:47.11091Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"]} {"level":"info","ts":"2026-07-30T00:54:47.110993Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.222: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-07-30T00:54:47.113342Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.128814ms"} {"level":"info","ts":"2026-07-30T00:54:47.119165Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5a9e84e0e0a90b63","cluster-id":"c7ddd50204a2b828"} {"level":"info","ts":"2026-07-30T00:54:47.119314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T00:54:47.119356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 0"} {"level":"info","ts":"2026-07-30T00:54:47.119379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5a9e84e0e0a90b63 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T00:54:47.11939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became follower at term 1"} {"level":"info","ts":"2026-07-30T00:54:47.119454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"warn","ts":"2026-07-30T00:54:47.124561Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T00:54:47.126768Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T00:54:47.127988Z","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-07-30T00:54:47.130123Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5a9e84e0e0a90b63","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T00:54:47.130283Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5a9e84e0e0a90b63","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T00:54:47.130372Z","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-07-30T00:54:47.13053Z","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-07-30T00:54:47.130553Z","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-07-30T00:54:47.132213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 switched to configuration voters=(6529802611110513507)"} {"level":"info","ts":"2026-07-30T00:54:47.1329Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","added-peer-id":"5a9e84e0e0a90b63","added-peer-peer-urls":["https://162.253.55.222:2380"]} {"level":"info","ts":"2026-07-30T00:54:47.134619Z","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-07-30T00:54:47.134744Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-07-30T00:54:47.134811Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.222:2380"} {"level":"info","ts":"2026-07-30T00:54:47.134952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5a9e84e0e0a90b63","initial-advertise-peer-urls":["https://162.253.55.222:2380"],"listen-peer-urls":["https://162.253.55.222:2380"],"advertise-client-urls":["https://162.253.55.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T00:54:47.13502Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T00:54:47.520707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T00:54:47.520776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T00:54:47.520817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgPreVoteResp from 5a9e84e0e0a90b63 at term 1"} {"level":"info","ts":"2026-07-30T00:54:47.520838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became candidate at term 2"} {"level":"info","ts":"2026-07-30T00:54:47.52085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 received MsgVoteResp from 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-07-30T00:54:47.520886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5a9e84e0e0a90b63 became leader at term 2"} {"level":"info","ts":"2026-07-30T00:54:47.5209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5a9e84e0e0a90b63 elected leader 5a9e84e0e0a90b63 at term 2"} {"level":"info","ts":"2026-07-30T00:54:47.521989Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:54:47.522814Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5a9e84e0e0a90b63","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.222:2379]}","request-path":"/0/members/5a9e84e0e0a90b63/attributes","cluster-id":"c7ddd50204a2b828","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T00:54:47.523027Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T00:54:47.523379Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7ddd50204a2b828","local-member-id":"5a9e84e0e0a90b63","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:54:47.523531Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:54:47.523574Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T00:54:47.523601Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T00:54:47.525767Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.222:2379"} {"level":"info","ts":"2026-07-30T00:54:47.526384Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T00:54:47.526435Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T00:54:47.52644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T00:55:13.622606Z","caller":"traceutil/trace.go:171","msg":"trace[1173883910] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"107.226288ms","start":"2026-07-30T00:55:13.51535Z","end":"2026-07-30T00:55:13.622576Z","steps":["trace[1173883910] 'process raft request' (duration: 107.074835ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:55:31.330623Z","caller":"traceutil/trace.go:171","msg":"trace[2039557949] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"118.932698ms","start":"2026-07-30T00:55:31.211651Z","end":"2026-07-30T00:55:31.330584Z","steps":["trace[2039557949] 'process raft request' (duration: 100.598133ms)","trace[2039557949] 'compare' (duration: 18.166062ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:55:31.537878Z","caller":"traceutil/trace.go:171","msg":"trace[1154284892] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"312.18754ms","start":"2026-07-30T00:55:31.225656Z","end":"2026-07-30T00:55:31.537844Z","steps":["trace[1154284892] 'process raft request' (duration: 251.931935ms)","trace[1154284892] 'compare' (duration: 59.935759ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:55:31.539512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:55:31.225641Z","time spent":"312.450496ms","remote":"127.0.0.1:60336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T00:55:31.540362Z","caller":"traceutil/trace.go:171","msg":"trace[1403310140] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"249.382672ms","start":"2026-07-30T00:55:31.290953Z","end":"2026-07-30T00:55:31.540335Z","steps":["trace[1403310140] 'process raft request' (duration: 247.972964ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:55:31.540244Z","caller":"traceutil/trace.go:171","msg":"trace[169230595] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:648; }","duration":"284.41287ms","start":"2026-07-30T00:55:31.255815Z","end":"2026-07-30T00:55:31.540228Z","steps":["trace[169230595] 'read index received' (duration: 56.504918ms)","trace[169230595] 'applied index is now lower than readState.Index' (duration: 227.906482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T00:55:31.540638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.796408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4bbw5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T00:55:31.540771Z","caller":"traceutil/trace.go:171","msg":"trace[1368514611] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4bbw5; range_end:; response_count:1; response_revision:634; }","duration":"284.976972ms","start":"2026-07-30T00:55:31.255774Z","end":"2026-07-30T00:55:31.54075Z","steps":["trace[1368514611] 'agreement among raft nodes before linearized reading' (duration: 284.686456ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:55:31.670216Z","caller":"traceutil/trace.go:171","msg":"trace[456495084] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"114.64348ms","start":"2026-07-30T00:55:31.555552Z","end":"2026-07-30T00:55:31.670195Z","steps":["trace[456495084] 'process raft request' (duration: 105.3838ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:55:32.126224Z","caller":"traceutil/trace.go:171","msg":"trace[642641214] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"413.013548ms","start":"2026-07-30T00:55:31.713132Z","end":"2026-07-30T00:55:32.126146Z","steps":["trace[642641214] 'process raft request' (duration: 410.417394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:55:32.126489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T00:55:31.713106Z","time spent":"413.244932ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T00:55:46.439525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.59664ms","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-07-30T00:55:46.439657Z","caller":"traceutil/trace.go:171","msg":"trace[786181894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"121.804334ms","start":"2026-07-30T00:55:46.317824Z","end":"2026-07-30T00:55:46.439629Z","steps":["trace[786181894] 'range keys from in-memory index tree' (duration: 121.272023ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:56:42.851601Z","caller":"traceutil/trace.go:171","msg":"trace[1289848251] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"112.213577ms","start":"2026-07-30T00:56:42.739347Z","end":"2026-07-30T00:56:42.851561Z","steps":["trace[1289848251] 'process raft request' (duration: 111.984273ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:57:06.569622Z","caller":"traceutil/trace.go:171","msg":"trace[1724835438] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"104.723309ms","start":"2026-07-30T00:57:06.464852Z","end":"2026-07-30T00:57:06.569575Z","steps":["trace[1724835438] 'process raft request' (duration: 104.359701ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:57:07.000642Z","caller":"traceutil/trace.go:171","msg":"trace[1861140154] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"144.133703ms","start":"2026-07-30T00:57:06.856471Z","end":"2026-07-30T00:57:07.000605Z","steps":["trace[1861140154] 'read index received' (duration: 143.773495ms)","trace[1861140154] 'applied index is now lower than readState.Index' (duration: 358.658µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:57:07.000832Z","caller":"traceutil/trace.go:171","msg":"trace[1476277120] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"172.461692ms","start":"2026-07-30T00:57:06.828356Z","end":"2026-07-30T00:57:07.000818Z","steps":["trace[1476277120] 'process raft request' (duration: 172.016303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:57:07.001101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.604032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T00:57:07.001216Z","caller":"traceutil/trace.go:171","msg":"trace[496576200] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1877; }","duration":"144.832447ms","start":"2026-07-30T00:57:06.856359Z","end":"2026-07-30T00:57:07.001191Z","steps":["trace[496576200] 'agreement among raft nodes before linearized reading' (duration: 144.632533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:57:07.001204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.866551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-30T00:57:07.001322Z","caller":"traceutil/trace.go:171","msg":"trace[552990774] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1877; }","duration":"102.018103ms","start":"2026-07-30T00:57:06.899268Z","end":"2026-07-30T00:57:07.001286Z","steps":["trace[552990774] 'agreement among raft nodes before linearized reading' (duration: 101.674536ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:57:23.239642Z","caller":"traceutil/trace.go:171","msg":"trace[154992042] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"121.000851ms","start":"2026-07-30T00:57:23.118614Z","end":"2026-07-30T00:57:23.239614Z","steps":["trace[154992042] 'read index received' (duration: 24.276946ms)","trace[154992042] 'applied index is now lower than readState.Index' (duration: 96.719064ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:57:23.239721Z","caller":"traceutil/trace.go:171","msg":"trace[1675469666] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"124.261307ms","start":"2026-07-30T00:57:23.115409Z","end":"2026-07-30T00:57:23.23967Z","steps":["trace[1675469666] 'process raft request' (duration: 124.017912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:57:23.239895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.266647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-30T00:57:23.239947Z","caller":"traceutil/trace.go:171","msg":"trace[671358835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2011; }","duration":"121.380379ms","start":"2026-07-30T00:57:23.118551Z","end":"2026-07-30T00:57:23.239932Z","steps":["trace[671358835] 'agreement among raft nodes before linearized reading' (duration: 121.191294ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:57:56.158498Z","caller":"traceutil/trace.go:171","msg":"trace[161126304] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"117.275903ms","start":"2026-07-30T00:57:56.041169Z","end":"2026-07-30T00:57:56.158445Z","steps":["trace[161126304] 'process raft request' (duration: 116.966057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:57:56.551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.351237ms","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-07-30T00:57:56.551136Z","caller":"traceutil/trace.go:171","msg":"trace[1613109511] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2193; }","duration":"123.51426ms","start":"2026-07-30T00:57:56.427603Z","end":"2026-07-30T00:57:56.551118Z","steps":["trace[1613109511] 'range keys from in-memory index tree' (duration: 123.331037ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:58:02.323654Z","caller":"traceutil/trace.go:171","msg":"trace[1019434286] linearizableReadLoop","detail":"{readStateIndex:2300; appliedIndex:2299; }","duration":"124.4941ms","start":"2026-07-30T00:58:02.199131Z","end":"2026-07-30T00:58:02.323625Z","steps":["trace[1019434286] 'read index received' (duration: 124.252415ms)","trace[1019434286] 'applied index is now lower than readState.Index' (duration: 240.675µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:58:02.323716Z","caller":"traceutil/trace.go:171","msg":"trace[1131170745] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"137.814473ms","start":"2026-07-30T00:58:02.185878Z","end":"2026-07-30T00:58:02.323692Z","steps":["trace[1131170745] 'process raft request' (duration: 137.565888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:58:02.323893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.741885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T00:58:02.323977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.90829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-30T00:58:02.323992Z","caller":"traceutil/trace.go:171","msg":"trace[666803900] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2223; }","duration":"124.863347ms","start":"2026-07-30T00:58:02.199107Z","end":"2026-07-30T00:58:02.323971Z","steps":["trace[666803900] 'agreement among raft nodes before linearized reading' (duration: 124.680914ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:58:02.324026Z","caller":"traceutil/trace.go:171","msg":"trace[825617460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2223; }","duration":"106.048914ms","start":"2026-07-30T00:58:02.217967Z","end":"2026-07-30T00:58:02.324016Z","steps":["trace[825617460] 'agreement among raft nodes before linearized reading' (duration: 105.82302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:58:02.585308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.426093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T00:58:02.585566Z","caller":"traceutil/trace.go:171","msg":"trace[1376293428] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2300; }","duration":"162.385374ms","start":"2026-07-30T00:58:02.423158Z","end":"2026-07-30T00:58:02.585544Z","steps":["trace[1376293428] 'read index received' (duration: 27.383849ms)","trace[1376293428] 'applied index is now lower than readState.Index' (duration: 134.997255ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:58:02.585611Z","caller":"traceutil/trace.go:171","msg":"trace[165866771] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"257.155458ms","start":"2026-07-30T00:58:02.328393Z","end":"2026-07-30T00:58:02.585549Z","steps":["trace[165866771] 'process raft request' (duration: 122.127453ms)","trace[165866771] 'compare' (duration: 134.258629ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T00:58:02.585678Z","caller":"traceutil/trace.go:171","msg":"trace[1872535385] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"256.551166ms","start":"2026-07-30T00:58:02.329072Z","end":"2026-07-30T00:58:02.585623Z","steps":["trace[1872535385] 'process raft request' (duration: 256.400173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:58:02.585744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.595048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T00:58:02.585785Z","caller":"traceutil/trace.go:171","msg":"trace[626763713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2225; }","duration":"162.66479ms","start":"2026-07-30T00:58:02.42311Z","end":"2026-07-30T00:58:02.585775Z","steps":["trace[626763713] 'agreement among raft nodes before linearized reading' (duration: 162.503457ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T00:59:11.147241Z","caller":"traceutil/trace.go:171","msg":"trace[1520267927] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"126.669557ms","start":"2026-07-30T00:59:11.020547Z","end":"2026-07-30T00:59:11.147217Z","steps":["trace[1520267927] 'process raft request' (duration: 117.803626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T00:59:11.466694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.273466ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-30T00:59:11.467024Z","caller":"traceutil/trace.go:171","msg":"trace[162001598] transaction","detail":"{read_only:false; response_revision:2796; number_of_response:1; }","duration":"278.018427ms","start":"2026-07-30T00:59:11.188984Z","end":"2026-07-30T00:59:11.467002Z","steps":["trace[162001598] 'process raft request' (duration: 277.821083ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:03:14.830722Z","caller":"traceutil/trace.go:171","msg":"trace[2123076636] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"157.209057ms","start":"2026-07-30T01:03:14.673473Z","end":"2026-07-30T01:03:14.830682Z","steps":["trace[2123076636] 'process raft request' (duration: 96.796109ms)","trace[2123076636] 'compare' (duration: 60.103722ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:03:14.83891Z","caller":"traceutil/trace.go:171","msg":"trace[253595013] transaction","detail":"{read_only:false; response_revision:5017; number_of_response:1; }","duration":"155.195116ms","start":"2026-07-30T01:03:14.683681Z","end":"2026-07-30T01:03:14.838876Z","steps":["trace[253595013] 'process raft request' (duration: 155.038153ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:03:14.839023Z","caller":"traceutil/trace.go:171","msg":"trace[799456950] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"157.828509ms","start":"2026-07-30T01:03:14.680962Z","end":"2026-07-30T01:03:14.838791Z","steps":["trace[799456950] 'process raft request' (duration: 157.608735ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:03:15.040874Z","caller":"traceutil/trace.go:171","msg":"trace[1390607505] transaction","detail":"{read_only:false; response_revision:5018; number_of_response:1; }","duration":"196.944512ms","start":"2026-07-30T01:03:14.843885Z","end":"2026-07-30T01:03:15.04083Z","steps":["trace[1390607505] 'process raft request' (duration: 123.421989ms)","trace[1390607505] 'compare' (duration: 73.217347ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:03:15.040944Z","caller":"traceutil/trace.go:171","msg":"trace[868733997] linearizableReadLoop","detail":"{readStateIndex:5197; appliedIndex:5195; }","duration":"195.33481ms","start":"2026-07-30T01:03:14.845576Z","end":"2026-07-30T01:03:15.040911Z","steps":["trace[868733997] 'read index received' (duration: 121.750846ms)","trace[868733997] 'applied index is now lower than readState.Index' (duration: 73.582834ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:03:15.041251Z","caller":"traceutil/trace.go:171","msg":"trace[1938484924] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"191.83195ms","start":"2026-07-30T01:03:14.849374Z","end":"2026-07-30T01:03:15.041206Z","steps":["trace[1938484924] 'process raft request' (duration: 191.487302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:03:15.041393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.80093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T01:03:15.041453Z","caller":"traceutil/trace.go:171","msg":"trace[1633516175] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:5020; }","duration":"195.919883ms","start":"2026-07-30T01:03:14.845518Z","end":"2026-07-30T01:03:15.041438Z","steps":["trace[1633516175] 'agreement among raft nodes before linearized reading' (duration: 195.80341ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:03:15.04133Z","caller":"traceutil/trace.go:171","msg":"trace[1385046521] transaction","detail":"{read_only:false; response_revision:5019; number_of_response:1; }","duration":"195.850671ms","start":"2026-07-30T01:03:14.845465Z","end":"2026-07-30T01:03:15.041316Z","steps":["trace[1385046521] 'process raft request' (duration: 195.312149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:03:15.041832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.36754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11455"} {"level":"info","ts":"2026-07-30T01:03:15.041893Z","caller":"traceutil/trace.go:171","msg":"trace[80490410] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5020; }","duration":"193.492613ms","start":"2026-07-30T01:03:14.848385Z","end":"2026-07-30T01:03:15.041878Z","steps":["trace[80490410] 'agreement among raft nodes before linearized reading' (duration: 193.275309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:03:15.042241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.099518ms","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-07-30T01:03:15.04232Z","caller":"traceutil/trace.go:171","msg":"trace[124922490] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5020; }","duration":"114.180381ms","start":"2026-07-30T01:03:14.92812Z","end":"2026-07-30T01:03:15.0423Z","steps":["trace[124922490] 'agreement among raft nodes before linearized reading' (duration: 113.962426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:03:15.04246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.232367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-30T01:03:15.04258Z","caller":"traceutil/trace.go:171","msg":"trace[215292315] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5020; }","duration":"103.541474ms","start":"2026-07-30T01:03:14.939014Z","end":"2026-07-30T01:03:15.042555Z","steps":["trace[215292315] 'agreement among raft nodes before linearized reading' (duration: 103.111655ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:03:17.193773Z","caller":"traceutil/trace.go:171","msg":"trace[87640836] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"111.554776ms","start":"2026-07-30T01:03:17.082182Z","end":"2026-07-30T01:03:17.193737Z","steps":["trace[87640836] 'process raft request' (duration: 90.629151ms)","trace[87640836] 'compare' (duration: 20.786242ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:03:22.994782Z","caller":"traceutil/trace.go:171","msg":"trace[1858875361] linearizableReadLoop","detail":"{readStateIndex:5387; appliedIndex:5386; }","duration":"133.308948ms","start":"2026-07-30T01:03:22.86143Z","end":"2026-07-30T01:03:22.994739Z","steps":["trace[1858875361] 'read index received' (duration: 107.80707ms)","trace[1858875361] 'applied index is now lower than readState.Index' (duration: 25.500368ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:03:22.994874Z","caller":"traceutil/trace.go:171","msg":"trace[1485577360] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"138.778749ms","start":"2026-07-30T01:03:22.856061Z","end":"2026-07-30T01:03:22.994839Z","steps":["trace[1485577360] 'process raft request' (duration: 113.159249ms)","trace[1485577360] 'compare' (duration: 25.350905ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:03:22.995109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.647265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-30T01:03:22.995186Z","caller":"traceutil/trace.go:171","msg":"trace[60431004] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:5202; }","duration":"133.792878ms","start":"2026-07-30T01:03:22.861378Z","end":"2026-07-30T01:03:22.995171Z","steps":["trace[60431004] 'agreement among raft nodes before linearized reading' (duration: 133.551903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:03:28.234461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.161204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T01:03:28.234742Z","caller":"traceutil/trace.go:171","msg":"trace[1739064582] linearizableReadLoop","detail":"{readStateIndex:5478; appliedIndex:5476; }","duration":"204.268358ms","start":"2026-07-30T01:03:28.030445Z","end":"2026-07-30T01:03:28.234713Z","steps":["trace[1739064582] 'read index received' (duration: 71.847989ms)","trace[1739064582] 'applied index is now lower than readState.Index' (duration: 132.418719ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:03:28.234828Z","caller":"traceutil/trace.go:171","msg":"trace[1172240074] transaction","detail":"{read_only:false; response_revision:5290; number_of_response:1; }","duration":"239.109885ms","start":"2026-07-30T01:03:27.995663Z","end":"2026-07-30T01:03:28.234773Z","steps":["trace[1172240074] 'process raft request' (duration: 106.546003ms)","trace[1172240074] 'compare' (duration: 131.95422ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:03:28.234884Z","caller":"traceutil/trace.go:171","msg":"trace[1647561297] transaction","detail":"{read_only:false; response_revision:5291; number_of_response:1; }","duration":"239.095575ms","start":"2026-07-30T01:03:27.995712Z","end":"2026-07-30T01:03:28.234808Z","steps":["trace[1647561297] 'process raft request' (duration: 238.932322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:03:28.235018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.567534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-cqspd\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-07-30T01:03:28.23506Z","caller":"traceutil/trace.go:171","msg":"trace[263779909] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-cqspd; range_end:; response_count:1; response_revision:5291; }","duration":"204.614865ms","start":"2026-07-30T01:03:28.030431Z","end":"2026-07-30T01:03:28.235045Z","steps":["trace[263779909] 'agreement among raft nodes before linearized reading' (duration: 204.36233ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:03:36.331718Z","caller":"traceutil/trace.go:171","msg":"trace[1480793414] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"139.927542ms","start":"2026-07-30T01:03:36.191766Z","end":"2026-07-30T01:03:36.331694Z","steps":["trace[1480793414] 'process raft request' (duration: 139.741258ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:03:46.633241Z","caller":"traceutil/trace.go:171","msg":"trace[302265527] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"104.771907ms","start":"2026-07-30T01:03:46.528381Z","end":"2026-07-30T01:03:46.633153Z","steps":["trace[302265527] 'process raft request' (duration: 104.453031ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:04:48.070785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2998} {"level":"info","ts":"2026-07-30T01:04:48.150426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2998,"took":"77.688318ms","hash":775614218,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":34144256,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-30T01:04:48.150519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":775614218,"revision":2998,"compact-revision":-1} {"level":"info","ts":"2026-07-30T01:09:48.083052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6119} {"level":"info","ts":"2026-07-30T01:09:48.1816Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6119,"took":"96.013724ms","hash":2569356309,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":23859200,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-30T01:09:48.181691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2569356309,"revision":6119,"compact-revision":2998} {"level":"warn","ts":"2026-07-30T01:09:49.703699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.901166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-07-30T01:09:49.70371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.445179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122599"} {"level":"info","ts":"2026-07-30T01:09:49.703775Z","caller":"traceutil/trace.go:171","msg":"trace[1972213644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8321; }","duration":"123.004858ms","start":"2026-07-30T01:09:49.580746Z","end":"2026-07-30T01:09:49.703751Z","steps":["trace[1972213644] 'range keys from in-memory index tree' (duration: 122.806704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:09:49.70379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.273617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-30T01:09:49.703824Z","caller":"traceutil/trace.go:171","msg":"trace[337852614] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8321; }","duration":"275.621303ms","start":"2026-07-30T01:09:49.428177Z","end":"2026-07-30T01:09:49.703798Z","steps":["trace[337852614] 'range keys from in-memory index tree' (duration: 275.189504ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:09:49.70386Z","caller":"traceutil/trace.go:171","msg":"trace[263627465] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8321; }","duration":"281.372629ms","start":"2026-07-30T01:09:49.422463Z","end":"2026-07-30T01:09:49.703835Z","steps":["trace[263627465] 'range keys from in-memory index tree' (duration: 281.082743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:09:49.703861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.27165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T01:09:49.703943Z","caller":"traceutil/trace.go:171","msg":"trace[1003016315] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:8321; }","duration":"236.394833ms","start":"2026-07-30T01:09:49.467527Z","end":"2026-07-30T01:09:49.703921Z","steps":["trace[1003016315] 'count revisions from in-memory index tree' (duration: 236.179789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:09:49.703302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.465357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T01:09:49.704258Z","caller":"traceutil/trace.go:171","msg":"trace[1802948034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8321; }","duration":"261.555279ms","start":"2026-07-30T01:09:49.442688Z","end":"2026-07-30T01:09:49.704244Z","steps":["trace[1802948034] 'range keys from in-memory index tree' (duration: 260.359965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:09:49.704887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.265093ms","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-07-30T01:09:49.704931Z","caller":"traceutil/trace.go:171","msg":"trace[167601557] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8321; }","duration":"124.419226ms","start":"2026-07-30T01:09:49.580501Z","end":"2026-07-30T01:09:49.70492Z","steps":["trace[167601557] 'count revisions from in-memory index tree' (duration: 124.181352ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:10:01.949039Z","caller":"traceutil/trace.go:171","msg":"trace[204716491] linearizableReadLoop","detail":"{readStateIndex:8671; appliedIndex:8670; }","duration":"160.087895ms","start":"2026-07-30T01:10:01.788917Z","end":"2026-07-30T01:10:01.949005Z","steps":["trace[204716491] 'read index received' (duration: 159.81586ms)","trace[204716491] 'applied index is now lower than readState.Index' (duration: 270.125µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:10:01.949204Z","caller":"traceutil/trace.go:171","msg":"trace[272256983] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"161.977823ms","start":"2026-07-30T01:10:01.787166Z","end":"2026-07-30T01:10:01.949144Z","steps":["trace[272256983] 'process raft request' (duration: 161.608626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:10:01.949655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.709197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223858"} {"level":"info","ts":"2026-07-30T01:10:01.949734Z","caller":"traceutil/trace.go:171","msg":"trace[1639417619] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8386; }","duration":"160.85936ms","start":"2026-07-30T01:10:01.788857Z","end":"2026-07-30T01:10:01.949716Z","steps":["trace[1639417619] 'agreement among raft nodes before linearized reading' (duration: 160.279259ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:10:30.633942Z","caller":"traceutil/trace.go:171","msg":"trace[2009774454] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8883; }","duration":"209.542621ms","start":"2026-07-30T01:10:30.424374Z","end":"2026-07-30T01:10:30.633916Z","steps":["trace[2009774454] 'read index received' (duration: 209.435739ms)","trace[2009774454] 'applied index is now lower than readState.Index' (duration: 105.682µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:10:30.634236Z","caller":"traceutil/trace.go:171","msg":"trace[363287016] transaction","detail":"{read_only:false; response_revision:8591; number_of_response:1; }","duration":"306.603017ms","start":"2026-07-30T01:10:30.327621Z","end":"2026-07-30T01:10:30.634224Z","steps":["trace[363287016] 'process raft request' (duration: 306.147918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:10:30.63439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:10:30.32759Z","time spent":"306.72363ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T01:10:30.634674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.312197ms","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-07-30T01:10:30.634729Z","caller":"traceutil/trace.go:171","msg":"trace[395815090] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8591; }","duration":"210.411569ms","start":"2026-07-30T01:10:30.424304Z","end":"2026-07-30T01:10:30.634716Z","steps":["trace[395815090] 'agreement among raft nodes before linearized reading' (duration: 210.250766ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:10:33.128266Z","caller":"traceutil/trace.go:171","msg":"trace[1711287584] transaction","detail":"{read_only:false; response_revision:8605; number_of_response:1; }","duration":"101.28535ms","start":"2026-07-30T01:10:33.026941Z","end":"2026-07-30T01:10:33.128226Z","steps":["trace[1711287584] 'process raft request' (duration: 101.094976ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:10:33.272239Z","caller":"traceutil/trace.go:171","msg":"trace[25788235] transaction","detail":"{read_only:false; response_revision:8606; number_of_response:1; }","duration":"139.5052ms","start":"2026-07-30T01:10:33.132692Z","end":"2026-07-30T01:10:33.272198Z","steps":["trace[25788235] 'process raft request' (duration: 114.383574ms)","trace[25788235] 'compare' (duration: 24.941712ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:10:33.337443Z","caller":"traceutil/trace.go:171","msg":"trace[61724423] linearizableReadLoop","detail":"{readStateIndex:8902; appliedIndex:8899; }","duration":"202.964589ms","start":"2026-07-30T01:10:33.134453Z","end":"2026-07-30T01:10:33.337417Z","steps":["trace[61724423] 'read index received' (duration: 112.646159ms)","trace[61724423] 'applied index is now lower than readState.Index' (duration: 90.31713ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:10:33.337508Z","caller":"traceutil/trace.go:171","msg":"trace[662356798] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"204.255214ms","start":"2026-07-30T01:10:33.13321Z","end":"2026-07-30T01:10:33.337465Z","steps":["trace[662356798] 'process raft request' (duration: 204.01743ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:10:33.337524Z","caller":"traceutil/trace.go:171","msg":"trace[412433563] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"204.231714ms","start":"2026-07-30T01:10:33.13326Z","end":"2026-07-30T01:10:33.337492Z","steps":["trace[412433563] 'process raft request' (duration: 204.110512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:10:33.337765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.295436ms","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-07-30T01:10:33.337834Z","caller":"traceutil/trace.go:171","msg":"trace[1620331686] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8608; }","duration":"203.406918ms","start":"2026-07-30T01:10:33.134412Z","end":"2026-07-30T01:10:33.337819Z","steps":["trace[1620331686] 'agreement among raft nodes before linearized reading' (duration: 203.112273ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:10:44.224355Z","caller":"traceutil/trace.go:171","msg":"trace[366771165] transaction","detail":"{read_only:false; response_revision:8709; number_of_response:1; }","duration":"253.971957ms","start":"2026-07-30T01:10:43.970327Z","end":"2026-07-30T01:10:44.224299Z","steps":["trace[366771165] 'process raft request' (duration: 253.656151ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:11:14.674077Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002473.wal"} {"level":"info","ts":"2026-07-30T01:12:30.150298Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T01:12:30.15682Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T01:12:30.156944Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-30T01:14:48.089828Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8312} {"level":"info","ts":"2026-07-30T01:14:48.171992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8312,"took":"79.275946ms","hash":3587673794,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":26476544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-30T01:14:48.172076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3587673794,"revision":8312,"compact-revision":6119} {"level":"info","ts":"2026-07-30T01:15:41.006504Z","caller":"traceutil/trace.go:171","msg":"trace[1507299094] transaction","detail":"{read_only:false; response_revision:11285; number_of_response:1; }","duration":"101.037157ms","start":"2026-07-30T01:15:40.90544Z","end":"2026-07-30T01:15:41.006477Z","steps":["trace[1507299094] 'process raft request' (duration: 100.860233ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:15:41.384445Z","caller":"traceutil/trace.go:171","msg":"trace[2115316353] transaction","detail":"{read_only:false; response_revision:11290; number_of_response:1; }","duration":"117.956149ms","start":"2026-07-30T01:15:41.266462Z","end":"2026-07-30T01:15:41.384418Z","steps":["trace[2115316353] 'process raft request' (duration: 84.90628ms)","trace[2115316353] 'compare' (duration: 32.851685ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:19:48.096309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10798} {"level":"info","ts":"2026-07-30T01:19:48.183981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10798,"took":"84.907234ms","hash":3775202114,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":26968064,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-30T01:19:48.18407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3775202114,"revision":10798,"compact-revision":8312} {"level":"info","ts":"2026-07-30T01:21:48.204961Z","caller":"traceutil/trace.go:171","msg":"trace[537824846] transaction","detail":"{read_only:false; response_revision:13772; number_of_response:1; }","duration":"225.790093ms","start":"2026-07-30T01:21:47.979146Z","end":"2026-07-30T01:21:48.204936Z","steps":["trace[537824846] 'process raft request' (duration: 155.02922ms)","trace[537824846] 'compare' (duration: 70.646111ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:21:48.206453Z","caller":"traceutil/trace.go:171","msg":"trace[1672517882] transaction","detail":"{read_only:false; response_revision:13773; number_of_response:1; }","duration":"186.767934ms","start":"2026-07-30T01:21:48.019661Z","end":"2026-07-30T01:21:48.206429Z","steps":["trace[1672517882] 'process raft request' (duration: 186.615011ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:22:04.478628Z","caller":"traceutil/trace.go:171","msg":"trace[1850417597] linearizableReadLoop","detail":"{readStateIndex:14337; appliedIndex:14336; }","duration":"193.025139ms","start":"2026-07-30T01:22:04.285562Z","end":"2026-07-30T01:22:04.478587Z","steps":["trace[1850417597] 'read index received' (duration: 192.734653ms)","trace[1850417597] 'applied index is now lower than readState.Index' (duration: 288.726µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:22:04.478713Z","caller":"traceutil/trace.go:171","msg":"trace[521943907] transaction","detail":"{read_only:false; response_revision:13870; number_of_response:1; }","duration":"214.946374ms","start":"2026-07-30T01:22:04.263733Z","end":"2026-07-30T01:22:04.478679Z","steps":["trace[521943907] 'process raft request' (duration: 214.616177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:22:04.478981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.254038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T01:22:04.479056Z","caller":"traceutil/trace.go:171","msg":"trace[1627888007] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:13870; }","duration":"127.420982ms","start":"2026-07-30T01:22:04.351614Z","end":"2026-07-30T01:22:04.479035Z","steps":["trace[1627888007] 'agreement among raft nodes before linearized reading' (duration: 127.252018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:22:04.47906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.498301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-30T01:22:04.47913Z","caller":"traceutil/trace.go:171","msg":"trace[2095646609] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13870; }","duration":"193.612863ms","start":"2026-07-30T01:22:04.285498Z","end":"2026-07-30T01:22:04.479111Z","steps":["trace[2095646609] 'agreement among raft nodes before linearized reading' (duration: 193.312456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:22:04.685503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.875284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T01:22:04.685661Z","caller":"traceutil/trace.go:171","msg":"trace[1165347298] linearizableReadLoop","detail":"{readStateIndex:14338; appliedIndex:14337; }","duration":"177.444904ms","start":"2026-07-30T01:22:04.508193Z","end":"2026-07-30T01:22:04.685637Z","steps":["trace[1165347298] 'read index received' (duration: 75.244614ms)","trace[1165347298] 'applied index is now lower than readState.Index' (duration: 102.19866ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:22:04.685833Z","caller":"traceutil/trace.go:171","msg":"trace[1880142851] transaction","detail":"{read_only:false; response_revision:13871; number_of_response:1; }","duration":"201.122124ms","start":"2026-07-30T01:22:04.484644Z","end":"2026-07-30T01:22:04.685766Z","steps":["trace[1880142851] 'process raft request' (duration: 98.846653ms)","trace[1880142851] 'compare' (duration: 101.673989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:22:04.68591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.709359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T01:22:04.685958Z","caller":"traceutil/trace.go:171","msg":"trace[1681620588] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:13871; }","duration":"177.815671ms","start":"2026-07-30T01:22:04.508126Z","end":"2026-07-30T01:22:04.685942Z","steps":["trace[1681620588] 'agreement among raft nodes before linearized reading' (duration: 177.715159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:22:26.247034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.281773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T01:22:26.247247Z","caller":"traceutil/trace.go:171","msg":"trace[1878803815] transaction","detail":"{read_only:false; response_revision:14177; number_of_response:1; }","duration":"165.047774ms","start":"2026-07-30T01:22:26.08218Z","end":"2026-07-30T01:22:26.247228Z","steps":["trace[1878803815] 'process raft request' (duration: 164.981202ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:22:26.247297Z","caller":"traceutil/trace.go:171","msg":"trace[160783384] transaction","detail":"{read_only:false; response_revision:14176; number_of_response:1; }","duration":"168.410932ms","start":"2026-07-30T01:22:26.078842Z","end":"2026-07-30T01:22:26.247253Z","steps":["trace[160783384] 'process raft request' (duration: 21.804922ms)","trace[160783384] 'compare' (duration: 146.135551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:23:42.828281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.088911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lw5g5\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-30T01:23:42.82839Z","caller":"traceutil/trace.go:171","msg":"trace[763242362] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lw5g5; range_end:; response_count:1; response_revision:14938; }","duration":"116.265485ms","start":"2026-07-30T01:23:42.7121Z","end":"2026-07-30T01:23:42.828366Z","steps":["trace[763242362] 'range keys from in-memory index tree' (duration: 115.823916ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:23:43.890675Z","caller":"traceutil/trace.go:171","msg":"trace[678464160] linearizableReadLoop","detail":"{readStateIndex:15442; appliedIndex:15441; }","duration":"206.649225ms","start":"2026-07-30T01:23:43.683998Z","end":"2026-07-30T01:23:43.890648Z","steps":["trace[678464160] 'read index received' (duration: 206.434931ms)","trace[678464160] 'applied index is now lower than readState.Index' (duration: 212.744µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:23:43.890802Z","caller":"traceutil/trace.go:171","msg":"trace[1976315779] transaction","detail":"{read_only:false; response_revision:14941; number_of_response:1; }","duration":"238.222526ms","start":"2026-07-30T01:23:43.652534Z","end":"2026-07-30T01:23:43.890756Z","steps":["trace[1976315779] 'process raft request' (duration: 237.93283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:23:43.890968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.945492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-30T01:23:43.891017Z","caller":"traceutil/trace.go:171","msg":"trace[1700346723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14941; }","duration":"207.096346ms","start":"2026-07-30T01:23:43.683908Z","end":"2026-07-30T01:23:43.891005Z","steps":["trace[1700346723] 'agreement among raft nodes before linearized reading' (duration: 206.897711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:23:43.89105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.690732ms","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-07-30T01:23:43.891135Z","caller":"traceutil/trace.go:171","msg":"trace[1175969506] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14941; }","duration":"186.822595ms","start":"2026-07-30T01:23:43.704296Z","end":"2026-07-30T01:23:43.891118Z","steps":["trace[1175969506] 'agreement among raft nodes before linearized reading' (duration: 186.572659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:23:43.891302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.582129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-jt2pv\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-07-30T01:23:43.891457Z","caller":"traceutil/trace.go:171","msg":"trace[733088162] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-jt2pv; range_end:; response_count:1; response_revision:14941; }","duration":"101.748061ms","start":"2026-07-30T01:23:43.789641Z","end":"2026-07-30T01:23:43.891389Z","steps":["trace[733088162] 'agreement among raft nodes before linearized reading' (duration: 101.339733ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:24:48.102535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12870} {"level":"info","ts":"2026-07-30T01:24:48.180428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12870,"took":"74.738384ms","hash":1734430785,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":29728768,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-30T01:24:48.180481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1734430785,"revision":12870,"compact-revision":10798} {"level":"warn","ts":"2026-07-30T01:29:44.618774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.754271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:29:44.618847Z","caller":"traceutil/trace.go:171","msg":"trace[772935862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17590; }","duration":"176.884503ms","start":"2026-07-30T01:29:44.441947Z","end":"2026-07-30T01:29:44.618831Z","steps":["trace[772935862] 'range keys from in-memory index tree' (duration: 176.635968ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:29:45.395565Z","caller":"traceutil/trace.go:171","msg":"trace[1615647043] transaction","detail":"{read_only:false; response_revision:17591; number_of_response:1; }","duration":"113.090277ms","start":"2026-07-30T01:29:45.28245Z","end":"2026-07-30T01:29:45.39554Z","steps":["trace[1615647043] 'process raft request' (duration: 112.930214ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:29:45.599358Z","caller":"traceutil/trace.go:171","msg":"trace[455671226] transaction","detail":"{read_only:false; response_revision:17592; number_of_response:1; }","duration":"313.395378ms","start":"2026-07-30T01:29:45.285927Z","end":"2026-07-30T01:29:45.599323Z","steps":["trace[455671226] 'process raft request' (duration: 293.409422ms)","trace[455671226] 'compare' (duration: 19.166319ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:29:45.59953Z","caller":"traceutil/trace.go:171","msg":"trace[602878445] linearizableReadLoop","detail":"{readStateIndex:18180; appliedIndex:18178; }","duration":"224.678945ms","start":"2026-07-30T01:29:45.374805Z","end":"2026-07-30T01:29:45.599484Z","steps":["trace[602878445] 'read index received' (duration: 20.644739ms)","trace[602878445] 'applied index is now lower than readState.Index' (duration: 204.030706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:29:45.599603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:29:45.285919Z","time spent":"313.53731ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T01:29:45.599832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.993081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:29:45.599906Z","caller":"traceutil/trace.go:171","msg":"trace[1320640338] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:0; response_revision:17593; }","duration":"225.121704ms","start":"2026-07-30T01:29:45.374764Z","end":"2026-07-30T01:29:45.599886Z","steps":["trace[1320640338] 'agreement among raft nodes before linearized reading' (duration: 224.970721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:29:45.600084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.00648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:29:45.600143Z","caller":"traceutil/trace.go:171","msg":"trace[1343092146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17593; }","duration":"158.083622ms","start":"2026-07-30T01:29:45.442045Z","end":"2026-07-30T01:29:45.600128Z","steps":["trace[1343092146] 'agreement among raft nodes before linearized reading' (duration: 157.99138ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:29:48.108654Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15334} {"level":"info","ts":"2026-07-30T01:29:48.213707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15334,"took":"100.301077ms","hash":2908769492,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":32174080,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-30T01:29:48.213873Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2908769492,"revision":15334,"compact-revision":12870} {"level":"info","ts":"2026-07-30T01:33:18.977014Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T01:33:18.98217Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-30T01:33:18.98227Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-30T01:34:48.115893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17680} {"level":"info","ts":"2026-07-30T01:34:48.227191Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17680,"took":"105.865711ms","hash":2887589742,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":33325056,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-30T01:34:48.227331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2887589742,"revision":17680,"compact-revision":15334} {"level":"info","ts":"2026-07-30T01:35:25.804888Z","caller":"traceutil/trace.go:171","msg":"trace[192065888] transaction","detail":"{read_only:false; response_revision:20239; number_of_response:1; }","duration":"129.409809ms","start":"2026-07-30T01:35:25.67542Z","end":"2026-07-30T01:35:25.804829Z","steps":["trace[192065888] 'process raft request' (duration: 129.192805ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:35:25.850078Z","caller":"traceutil/trace.go:171","msg":"trace[1818862917] linearizableReadLoop","detail":"{readStateIndex:20918; appliedIndex:20916; }","duration":"132.583693ms","start":"2026-07-30T01:35:25.717474Z","end":"2026-07-30T01:35:25.850058Z","steps":["trace[1818862917] 'read index received' (duration: 87.17693ms)","trace[1818862917] 'applied index is now lower than readState.Index' (duration: 45.405793ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:35:25.850316Z","caller":"traceutil/trace.go:171","msg":"trace[307148643] transaction","detail":"{read_only:false; response_revision:20240; number_of_response:1; }","duration":"171.246169ms","start":"2026-07-30T01:35:25.67906Z","end":"2026-07-30T01:35:25.850306Z","steps":["trace[307148643] 'process raft request' (duration: 170.841651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:35:25.850531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.029313ms","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-07-30T01:35:25.850557Z","caller":"traceutil/trace.go:171","msg":"trace[1661928484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20240; }","duration":"133.115214ms","start":"2026-07-30T01:35:25.717434Z","end":"2026-07-30T01:35:25.850549Z","steps":["trace[1661928484] 'agreement among raft nodes before linearized reading' (duration: 133.005111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:35:25.850679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.836398ms","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-07-30T01:35:25.850698Z","caller":"traceutil/trace.go:171","msg":"trace[230141219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20240; }","duration":"132.873389ms","start":"2026-07-30T01:35:25.71782Z","end":"2026-07-30T01:35:25.850693Z","steps":["trace[230141219] 'agreement among raft nodes before linearized reading' (duration: 132.813838ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:35:25.982341Z","caller":"traceutil/trace.go:171","msg":"trace[970101139] transaction","detail":"{read_only:false; response_revision:20241; number_of_response:1; }","duration":"127.418099ms","start":"2026-07-30T01:35:25.854893Z","end":"2026-07-30T01:35:25.982311Z","steps":["trace[970101139] 'process raft request' (duration: 119.373185ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:35:26.025209Z","caller":"traceutil/trace.go:171","msg":"trace[497782712] transaction","detail":"{read_only:false; response_revision:20243; number_of_response:1; }","duration":"169.471933ms","start":"2026-07-30T01:35:25.855695Z","end":"2026-07-30T01:35:26.025167Z","steps":["trace[497782712] 'process raft request' (duration: 169.340281ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:35:26.02525Z","caller":"traceutil/trace.go:171","msg":"trace[1481634492] transaction","detail":"{read_only:false; response_revision:20242; number_of_response:1; }","duration":"169.87705ms","start":"2026-07-30T01:35:25.855351Z","end":"2026-07-30T01:35:26.025228Z","steps":["trace[1481634492] 'process raft request' (duration: 169.547374ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:35:26.02524Z","caller":"traceutil/trace.go:171","msg":"trace[1188640970] transaction","detail":"{read_only:false; response_revision:20244; number_of_response:1; }","duration":"169.399992ms","start":"2026-07-30T01:35:25.855787Z","end":"2026-07-30T01:35:26.025187Z","steps":["trace[1188640970] 'process raft request' (duration: 169.290449ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:35:26.151774Z","caller":"traceutil/trace.go:171","msg":"trace[106989916] transaction","detail":"{read_only:false; response_revision:20245; number_of_response:1; }","duration":"121.466929ms","start":"2026-07-30T01:35:26.030266Z","end":"2026-07-30T01:35:26.151733Z","steps":["trace[106989916] 'process raft request' (duration: 87.649261ms)","trace[106989916] 'compare' (duration: 33.633914ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:35:26.381184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.984083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:35:26.381624Z","caller":"traceutil/trace.go:171","msg":"trace[2054328858] linearizableReadLoop","detail":"{readStateIndex:20924; appliedIndex:20923; }","duration":"207.074667ms","start":"2026-07-30T01:35:26.174501Z","end":"2026-07-30T01:35:26.381576Z","steps":["trace[2054328858] 'read index received' (duration: 100.538573ms)","trace[2054328858] 'applied index is now lower than readState.Index' (duration: 106.532564ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:35:26.381602Z","caller":"traceutil/trace.go:171","msg":"trace[1026257186] transaction","detail":"{read_only:false; response_revision:20246; number_of_response:1; }","duration":"222.239486ms","start":"2026-07-30T01:35:26.159313Z","end":"2026-07-30T01:35:26.381553Z","steps":["trace[1026257186] 'process raft request' (duration: 115.771232ms)","trace[1026257186] 'compare' (duration: 105.83243ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:35:26.414657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.35793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:35:26.414739Z","caller":"traceutil/trace.go:171","msg":"trace[834423392] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:20246; }","duration":"136.509253ms","start":"2026-07-30T01:35:26.278213Z","end":"2026-07-30T01:35:26.414722Z","steps":["trace[834423392] 'agreement among raft nodes before linearized reading' (duration: 136.35825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:35:26.414734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.169189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T01:35:26.414887Z","caller":"traceutil/trace.go:171","msg":"trace[638435752] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:20246; }","duration":"240.466196ms","start":"2026-07-30T01:35:26.174386Z","end":"2026-07-30T01:35:26.414852Z","steps":["trace[638435752] 'agreement among raft nodes before linearized reading' (duration: 207.451145ms)","trace[638435752] 'count revisions from in-memory index tree' (duration: 32.762895ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:37:38.401845Z","caller":"traceutil/trace.go:171","msg":"trace[1556625862] transaction","detail":"{read_only:false; response_revision:21304; number_of_response:1; }","duration":"114.261902ms","start":"2026-07-30T01:37:38.287519Z","end":"2026-07-30T01:37:38.401781Z","steps":["trace[1556625862] 'process raft request' (duration: 114.034078ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:39:18.07287Z","caller":"traceutil/trace.go:171","msg":"trace[1295933122] transaction","detail":"{read_only:false; response_revision:22643; number_of_response:1; }","duration":"104.245219ms","start":"2026-07-30T01:39:17.968599Z","end":"2026-07-30T01:39:18.072845Z","steps":["trace[1295933122] 'process raft request' (duration: 103.608887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:39:18.413021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.98142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-07-30T01:39:18.413185Z","caller":"traceutil/trace.go:171","msg":"trace[1828086477] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:22643; }","duration":"206.130632ms","start":"2026-07-30T01:39:18.206968Z","end":"2026-07-30T01:39:18.413098Z","steps":["trace[1828086477] 'range keys from in-memory index tree' (duration: 205.785775ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:39:18.413662Z","caller":"traceutil/trace.go:171","msg":"trace[957261911] transaction","detail":"{read_only:false; response_revision:22644; number_of_response:1; }","duration":"139.501848ms","start":"2026-07-30T01:39:18.274035Z","end":"2026-07-30T01:39:18.413537Z","steps":["trace[957261911] 'process raft request' (duration: 103.143788ms)","trace[957261911] 'compare' (duration: 35.984692ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:39:48.124711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20019} {"level":"info","ts":"2026-07-30T01:39:48.238151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20019,"took":"106.097838ms","hash":501368144,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":42332160,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-30T01:39:48.238231Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":501368144,"revision":20019,"compact-revision":17680} {"level":"info","ts":"2026-07-30T01:40:35.857269Z","caller":"traceutil/trace.go:171","msg":"trace[610361906] transaction","detail":"{read_only:false; response_revision:23528; number_of_response:1; }","duration":"125.114574ms","start":"2026-07-30T01:40:35.732117Z","end":"2026-07-30T01:40:35.857232Z","steps":["trace[610361906] 'process raft request' (duration: 83.197242ms)","trace[610361906] 'compare' (duration: 41.510044ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:40:35.857589Z","caller":"traceutil/trace.go:171","msg":"trace[609683619] transaction","detail":"{read_only:false; response_revision:23529; number_of_response:1; }","duration":"106.630868ms","start":"2026-07-30T01:40:35.750948Z","end":"2026-07-30T01:40:35.857579Z","steps":["trace[609683619] 'process raft request' (duration: 106.150719ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:34.210458Z","caller":"traceutil/trace.go:171","msg":"trace[521421066] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"107.301742ms","start":"2026-07-30T01:42:34.10313Z","end":"2026-07-30T01:42:34.210431Z","steps":["trace[521421066] 'process raft request' (duration: 106.615328ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:39.417022Z","caller":"traceutil/trace.go:171","msg":"trace[924759102] linearizableReadLoop","detail":"{readStateIndex:25516; appliedIndex:25515; }","duration":"246.575584ms","start":"2026-07-30T01:42:39.170389Z","end":"2026-07-30T01:42:39.416965Z","steps":["trace[924759102] 'read index received' (duration: 161.35482ms)","trace[924759102] 'applied index is now lower than readState.Index' (duration: 85.219084ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:39.417243Z","caller":"traceutil/trace.go:171","msg":"trace[1771043304] transaction","detail":"{read_only:false; response_revision:24716; number_of_response:1; }","duration":"252.565956ms","start":"2026-07-30T01:42:39.164641Z","end":"2026-07-30T01:42:39.417206Z","steps":["trace[1771043304] 'process raft request' (duration: 167.086707ms)","trace[1771043304] 'compare' (duration: 84.604011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:39.417275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.860859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-07-30T01:42:39.417328Z","caller":"traceutil/trace.go:171","msg":"trace[753403894] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:24716; }","duration":"246.952802ms","start":"2026-07-30T01:42:39.17036Z","end":"2026-07-30T01:42:39.417313Z","steps":["trace[753403894] 'agreement among raft nodes before linearized reading' (duration: 246.745448ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:39.44284Z","caller":"traceutil/trace.go:171","msg":"trace[814706966] transaction","detail":"{read_only:false; response_revision:24717; number_of_response:1; }","duration":"226.410014ms","start":"2026-07-30T01:42:39.216392Z","end":"2026-07-30T01:42:39.442802Z","steps":["trace[814706966] 'process raft request' (duration: 226.19814ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:39.75541Z","caller":"traceutil/trace.go:171","msg":"trace[1842799319] transaction","detail":"{read_only:false; response_revision:24718; number_of_response:1; }","duration":"172.992428ms","start":"2026-07-30T01:42:39.582371Z","end":"2026-07-30T01:42:39.755363Z","steps":["trace[1842799319] 'process raft request' (duration: 89.017081ms)","trace[1842799319] 'compare' (duration: 83.526168ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:39.755507Z","caller":"traceutil/trace.go:171","msg":"trace[1314881938] transaction","detail":"{read_only:false; response_revision:24719; number_of_response:1; }","duration":"170.331994ms","start":"2026-07-30T01:42:39.585148Z","end":"2026-07-30T01:42:39.75548Z","steps":["trace[1314881938] 'process raft request' (duration: 170.157ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:39.755688Z","caller":"traceutil/trace.go:171","msg":"trace[2007342776] transaction","detail":"{read_only:false; response_revision:24720; number_of_response:1; }","duration":"132.059326ms","start":"2026-07-30T01:42:39.623617Z","end":"2026-07-30T01:42:39.755677Z","steps":["trace[2007342776] 'process raft request' (duration: 131.812821ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:39.755704Z","caller":"traceutil/trace.go:171","msg":"trace[1439754831] linearizableReadLoop","detail":"{readStateIndex:25519; appliedIndex:25517; }","duration":"141.667161ms","start":"2026-07-30T01:42:39.613987Z","end":"2026-07-30T01:42:39.755655Z","steps":["trace[1439754831] 'read index received' (duration: 57.424118ms)","trace[1439754831] 'applied index is now lower than readState.Index' (duration: 84.236653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:39.755932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.918426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-30T01:42:39.755991Z","caller":"traceutil/trace.go:171","msg":"trace[1626873965] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24720; }","duration":"142.013869ms","start":"2026-07-30T01:42:39.61396Z","end":"2026-07-30T01:42:39.755973Z","steps":["trace[1626873965] 'agreement among raft nodes before linearized reading' (duration: 141.832995ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:39.87881Z","caller":"traceutil/trace.go:171","msg":"trace[398442033] transaction","detail":"{read_only:false; response_revision:24721; number_of_response:1; }","duration":"114.496138ms","start":"2026-07-30T01:42:39.764285Z","end":"2026-07-30T01:42:39.878781Z","steps":["trace[398442033] 'process raft request' (duration: 114.298584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:40.697704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.58833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:42:40.697848Z","caller":"traceutil/trace.go:171","msg":"trace[1416766595] linearizableReadLoop","detail":"{readStateIndex:25524; appliedIndex:25523; }","duration":"255.450344ms","start":"2026-07-30T01:42:40.442377Z","end":"2026-07-30T01:42:40.697828Z","steps":["trace[1416766595] 'read index received' (duration: 112.617589ms)","trace[1416766595] 'applied index is now lower than readState.Index' (duration: 142.831055ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:40.697903Z","caller":"traceutil/trace.go:171","msg":"trace[148746432] transaction","detail":"{read_only:false; response_revision:24724; number_of_response:1; }","duration":"284.150839ms","start":"2026-07-30T01:42:40.413705Z","end":"2026-07-30T01:42:40.697856Z","steps":["trace[148746432] 'process raft request' (duration: 141.298413ms)","trace[148746432] 'compare' (duration: 142.030559ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:40.697987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.601228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:40.698069Z","caller":"traceutil/trace.go:171","msg":"trace[1739441917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24724; }","duration":"255.742091ms","start":"2026-07-30T01:42:40.442311Z","end":"2026-07-30T01:42:40.698053Z","steps":["trace[1739441917] 'agreement among raft nodes before linearized reading' (duration: 255.615508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:40.698197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.353151ms","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-07-30T01:42:40.69825Z","caller":"traceutil/trace.go:171","msg":"trace[2118377883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24724; }","duration":"160.483753ms","start":"2026-07-30T01:42:40.537754Z","end":"2026-07-30T01:42:40.698237Z","steps":["trace[2118377883] 'agreement among raft nodes before linearized reading' (duration: 160.206388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:40.698306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.219571ms","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":"warn","ts":"2026-07-30T01:42:40.698358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.015907ms","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-07-30T01:42:40.6984Z","caller":"traceutil/trace.go:171","msg":"trace[898771512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24724; }","duration":"108.349934ms","start":"2026-07-30T01:42:40.590029Z","end":"2026-07-30T01:42:40.698379Z","steps":["trace[898771512] 'agreement among raft nodes before linearized reading' (duration: 108.063158ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:40.698436Z","caller":"traceutil/trace.go:171","msg":"trace[409417453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24724; }","duration":"111.14098ms","start":"2026-07-30T01:42:40.587277Z","end":"2026-07-30T01:42:40.698418Z","steps":["trace[409417453] 'agreement among raft nodes before linearized reading' (duration: 110.916755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:40.698397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.210662ms","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-07-30T01:42:40.698504Z","caller":"traceutil/trace.go:171","msg":"trace[1782157263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24724; }","duration":"113.338784ms","start":"2026-07-30T01:42:40.585143Z","end":"2026-07-30T01:42:40.698481Z","steps":["trace[1782157263] 'agreement among raft nodes before linearized reading' (duration: 113.054698ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:40.888654Z","caller":"traceutil/trace.go:171","msg":"trace[1497869118] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"183.260826ms","start":"2026-07-30T01:42:40.705362Z","end":"2026-07-30T01:42:40.888623Z","steps":["trace[1497869118] 'process raft request' (duration: 182.953689ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:40.888917Z","caller":"traceutil/trace.go:171","msg":"trace[984755018] linearizableReadLoop","detail":"{readStateIndex:25528; appliedIndex:25525; }","duration":"181.372388ms","start":"2026-07-30T01:42:40.707527Z","end":"2026-07-30T01:42:40.8889Z","steps":["trace[984755018] 'read index received' (duration: 75.843853ms)","trace[984755018] 'applied index is now lower than readState.Index' (duration: 105.527785ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:40.888938Z","caller":"traceutil/trace.go:171","msg":"trace[680476838] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"183.572712ms","start":"2026-07-30T01:42:40.705336Z","end":"2026-07-30T01:42:40.888909Z","steps":["trace[680476838] 'process raft request' (duration: 183.239386ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:40.88904Z","caller":"traceutil/trace.go:171","msg":"trace[1973909722] transaction","detail":"{read_only:false; response_revision:24728; number_of_response:1; }","duration":"183.512201ms","start":"2026-07-30T01:42:40.70547Z","end":"2026-07-30T01:42:40.888982Z","steps":["trace[1973909722] 'process raft request' (duration: 183.357458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:40.889119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.583113ms","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-07-30T01:42:40.889182Z","caller":"traceutil/trace.go:171","msg":"trace[1460108705] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24728; }","duration":"181.732176ms","start":"2026-07-30T01:42:40.707441Z","end":"2026-07-30T01:42:40.889174Z","steps":["trace[1460108705] 'agreement among raft nodes before linearized reading' (duration: 181.544432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:41.160362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.404543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:42:41.16062Z","caller":"traceutil/trace.go:171","msg":"trace[1796235321] linearizableReadLoop","detail":"{readStateIndex:25530; appliedIndex:25528; }","duration":"264.60995ms","start":"2026-07-30T01:42:40.895986Z","end":"2026-07-30T01:42:41.160595Z","steps":["trace[1796235321] 'read index received' (duration: 128.798849ms)","trace[1796235321] 'applied index is now lower than readState.Index' (duration: 135.809981ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:41.160637Z","caller":"traceutil/trace.go:171","msg":"trace[140217629] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"324.776124ms","start":"2026-07-30T01:42:40.835821Z","end":"2026-07-30T01:42:41.160597Z","steps":["trace[140217629] 'process raft request' (duration: 189.038304ms)","trace[140217629] 'compare' (duration: 135.134978ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:41.160809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:40.835786Z","time spent":"324.928097ms","remote":"127.0.0.1:60522","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-07-30T01:42:41.160838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.864376ms","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-07-30T01:42:41.16086Z","caller":"traceutil/trace.go:171","msg":"trace[143687471] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24730; }","duration":"264.932397ms","start":"2026-07-30T01:42:40.895919Z","end":"2026-07-30T01:42:41.160851Z","steps":["trace[143687471] 'agreement among raft nodes before linearized reading' (duration: 264.755194ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:41.160819Z","caller":"traceutil/trace.go:171","msg":"trace[632139579] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"279.579416ms","start":"2026-07-30T01:42:40.881202Z","end":"2026-07-30T01:42:41.160782Z","steps":["trace[632139579] 'process raft request' (duration: 279.28907ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:41.650921Z","caller":"traceutil/trace.go:171","msg":"trace[403976233] linearizableReadLoop","detail":"{readStateIndex:25534; appliedIndex:25532; }","duration":"207.875577ms","start":"2026-07-30T01:42:41.442963Z","end":"2026-07-30T01:42:41.650839Z","steps":["trace[403976233] 'read index received' (duration: 3.034192ms)","trace[403976233] 'applied index is now lower than readState.Index' (duration: 204.839385ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:41.651086Z","caller":"traceutil/trace.go:171","msg":"trace[245256909] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"226.68412ms","start":"2026-07-30T01:42:41.424371Z","end":"2026-07-30T01:42:41.651055Z","steps":["trace[245256909] 'process raft request' (duration: 216.864591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:41.651196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.203644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:41.65129Z","caller":"traceutil/trace.go:171","msg":"trace[541632864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24733; }","duration":"208.376337ms","start":"2026-07-30T01:42:41.442887Z","end":"2026-07-30T01:42:41.651263Z","steps":["trace[541632864] 'agreement among raft nodes before linearized reading' (duration: 208.180673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:41.651467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.92723ms","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-07-30T01:42:41.651542Z","caller":"traceutil/trace.go:171","msg":"trace[1047735583] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24733; }","duration":"155.091554ms","start":"2026-07-30T01:42:41.496426Z","end":"2026-07-30T01:42:41.651518Z","steps":["trace[1047735583] 'agreement among raft nodes before linearized reading' (duration: 154.926411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:41.652038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.133291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-30T01:42:41.652123Z","caller":"traceutil/trace.go:171","msg":"trace[914181334] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24733; }","duration":"203.254534ms","start":"2026-07-30T01:42:41.448856Z","end":"2026-07-30T01:42:41.652111Z","steps":["trace[914181334] 'agreement among raft nodes before linearized reading' (duration: 202.212612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:42.089787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.700454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:42:42.089918Z","caller":"traceutil/trace.go:171","msg":"trace[386884003] transaction","detail":"{read_only:false; response_revision:24737; number_of_response:1; }","duration":"218.810339ms","start":"2026-07-30T01:42:41.871089Z","end":"2026-07-30T01:42:42.0899Z","steps":["trace[386884003] 'process raft request' (duration: 70.889821ms)","trace[386884003] 'compare' (duration: 147.576421ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:42.917842Z","caller":"traceutil/trace.go:171","msg":"trace[1246475822] transaction","detail":"{read_only:false; response_revision:24741; number_of_response:1; }","duration":"123.739177ms","start":"2026-07-30T01:42:42.794021Z","end":"2026-07-30T01:42:42.91776Z","steps":["trace[1246475822] 'process raft request' (duration: 121.551623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:43.376121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.492659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:42:43.376262Z","caller":"traceutil/trace.go:171","msg":"trace[1828402653] linearizableReadLoop","detail":"{readStateIndex:25547; appliedIndex:25546; }","duration":"114.434847ms","start":"2026-07-30T01:42:43.261808Z","end":"2026-07-30T01:42:43.376243Z","steps":["trace[1828402653] 'read index received' (duration: 42.521µs)","trace[1828402653] 'applied index is now lower than readState.Index' (duration: 114.391246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:43.376397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.60083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:43.376435Z","caller":"traceutil/trace.go:171","msg":"trace[1927526084] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24746; }","duration":"114.669212ms","start":"2026-07-30T01:42:43.261752Z","end":"2026-07-30T01:42:43.376421Z","steps":["trace[1927526084] 'agreement among raft nodes before linearized reading' (duration: 114.543239ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:43.376385Z","caller":"traceutil/trace.go:171","msg":"trace[1956340580] transaction","detail":"{read_only:false; response_revision:24746; number_of_response:1; }","duration":"207.963149ms","start":"2026-07-30T01:42:43.16837Z","end":"2026-07-30T01:42:43.376333Z","steps":["trace[1956340580] 'process raft request' (duration: 90.073632ms)","trace[1956340580] 'compare' (duration: 117.154492ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:43.97026Z","caller":"traceutil/trace.go:171","msg":"trace[281224247] transaction","detail":"{read_only:false; response_revision:24749; number_of_response:1; }","duration":"172.680262ms","start":"2026-07-30T01:42:43.797546Z","end":"2026-07-30T01:42:43.970226Z","steps":["trace[281224247] 'process raft request' (duration: 172.483648ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:43.970581Z","caller":"traceutil/trace.go:171","msg":"trace[1058688568] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"172.521288ms","start":"2026-07-30T01:42:43.798024Z","end":"2026-07-30T01:42:43.970545Z","steps":["trace[1058688568] 'process raft request' (duration: 172.165951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:44.18123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.359445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:44.181335Z","caller":"traceutil/trace.go:171","msg":"trace[785618524] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24750; }","duration":"144.556499ms","start":"2026-07-30T01:42:44.03675Z","end":"2026-07-30T01:42:44.181306Z","steps":["trace[785618524] 'count revisions from in-memory index tree' (duration: 144.200952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:44.181291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.267394ms","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-07-30T01:42:44.18139Z","caller":"traceutil/trace.go:171","msg":"trace[691326498] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24750; }","duration":"148.443818ms","start":"2026-07-30T01:42:44.032929Z","end":"2026-07-30T01:42:44.181373Z","steps":["trace[691326498] 'range keys from in-memory index tree' (duration: 148.02979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:44.778942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.439452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:42:44.77912Z","caller":"traceutil/trace.go:171","msg":"trace[1152853881] linearizableReadLoop","detail":"{readStateIndex:25554; appliedIndex:25552; }","duration":"143.755903ms","start":"2026-07-30T01:42:44.635348Z","end":"2026-07-30T01:42:44.779104Z","steps":["trace[1152853881] 'read index received' (duration: 27.979679ms)","trace[1152853881] 'applied index is now lower than readState.Index' (duration: 115.775134ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:44.779408Z","caller":"traceutil/trace.go:171","msg":"trace[935496522] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"299.826367ms","start":"2026-07-30T01:42:44.479562Z","end":"2026-07-30T01:42:44.779388Z","steps":["trace[935496522] 'process raft request' (duration: 111.812914ms)","trace[935496522] 'compare' (duration: 186.741987ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:44.779633Z","caller":"traceutil/trace.go:171","msg":"trace[712692641] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"297.804475ms","start":"2026-07-30T01:42:44.481818Z","end":"2026-07-30T01:42:44.779623Z","steps":["trace[712692641] 'process raft request' (duration: 297.227804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:44.779889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.537898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T01:42:44.779929Z","caller":"traceutil/trace.go:171","msg":"trace[1115562567] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24753; }","duration":"144.63758ms","start":"2026-07-30T01:42:44.635283Z","end":"2026-07-30T01:42:44.77992Z","steps":["trace[1115562567] 'agreement among raft nodes before linearized reading' (duration: 144.499578ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:44.923809Z","caller":"traceutil/trace.go:171","msg":"trace[1628244640] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"133.141197ms","start":"2026-07-30T01:42:44.790606Z","end":"2026-07-30T01:42:44.923747Z","steps":["trace[1628244640] 'process raft request' (duration: 132.226399ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:55.204152Z","caller":"traceutil/trace.go:171","msg":"trace[494244509] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"132.398493ms","start":"2026-07-30T01:42:55.071723Z","end":"2026-07-30T01:42:55.204121Z","steps":["trace[494244509] 'process raft request' (duration: 132.208849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:55.324651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.133436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:42:55.324824Z","caller":"traceutil/trace.go:171","msg":"trace[1395656667] linearizableReadLoop","detail":"{readStateIndex:25639; appliedIndex:25637; }","duration":"201.962216ms","start":"2026-07-30T01:42:55.122842Z","end":"2026-07-30T01:42:55.324805Z","steps":["trace[1395656667] 'read index received' (duration: 81.031618ms)","trace[1395656667] 'applied index is now lower than readState.Index' (duration: 120.929478ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:55.324891Z","caller":"traceutil/trace.go:171","msg":"trace[162167138] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"252.181408ms","start":"2026-07-30T01:42:55.072698Z","end":"2026-07-30T01:42:55.32488Z","steps":["trace[162167138] 'process raft request' (duration: 149.722645ms)","trace[162167138] 'compare' (duration: 101.987424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:55.325201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.332814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:55.325277Z","caller":"traceutil/trace.go:171","msg":"trace[1427738626] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:24836; }","duration":"202.421886ms","start":"2026-07-30T01:42:55.122834Z","end":"2026-07-30T01:42:55.325256Z","steps":["trace[1427738626] 'agreement among raft nodes before linearized reading' (duration: 202.023528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.371392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.435778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:42:57.371596Z","caller":"traceutil/trace.go:171","msg":"trace[555822488] linearizableReadLoop","detail":"{readStateIndex:25648; appliedIndex:25647; }","duration":"495.128569ms","start":"2026-07-30T01:42:56.876451Z","end":"2026-07-30T01:42:57.371579Z","steps":["trace[555822488] 'read index received' (duration: 231.003338ms)","trace[555822488] 'applied index is now lower than readState.Index' (duration: 264.123171ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:42:57.371638Z","caller":"traceutil/trace.go:171","msg":"trace[1231150521] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"514.233327ms","start":"2026-07-30T01:42:56.85737Z","end":"2026-07-30T01:42:57.371603Z","steps":["trace[1231150521] 'process raft request' (duration: 250.117036ms)","trace[1231150521] 'compare' (duration: 263.094201ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:57.371723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.271321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T01:42:57.37178Z","caller":"traceutil/trace.go:171","msg":"trace[1173406005] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:24843; }","duration":"495.375583ms","start":"2026-07-30T01:42:56.87639Z","end":"2026-07-30T01:42:57.371766Z","steps":["trace[1173406005] 'agreement among raft nodes before linearized reading' (duration: 495.272001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.371823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:56.876366Z","time spent":"495.446695ms","remote":"127.0.0.1:58338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-30T01:42:57.371827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:56.857345Z","time spent":"514.373621ms","remote":"127.0.0.1:60522","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-07-30T01:42:57.37227Z","caller":"traceutil/trace.go:171","msg":"trace[824015641] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"261.698111ms","start":"2026-07-30T01:42:57.110556Z","end":"2026-07-30T01:42:57.372255Z","steps":["trace[824015641] 'process raft request' (duration: 261.600289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.384348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.838882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:57.384392Z","caller":"traceutil/trace.go:171","msg":"trace[728792395] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:24845; }","duration":"490.951674ms","start":"2026-07-30T01:42:56.89343Z","end":"2026-07-30T01:42:57.384382Z","steps":["trace[728792395] 'agreement among raft nodes before linearized reading' (duration: 490.850072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.38443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:56.893405Z","time spent":"491.005465ms","remote":"127.0.0.1:50060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T01:42:57.38486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.5185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-30T01:42:57.384882Z","caller":"traceutil/trace.go:171","msg":"trace[1169472572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24845; }","duration":"176.54127ms","start":"2026-07-30T01:42:57.208335Z","end":"2026-07-30T01:42:57.384877Z","steps":["trace[1169472572] 'agreement among raft nodes before linearized reading' (duration: 176.488429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.384946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.713659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:57.384966Z","caller":"traceutil/trace.go:171","msg":"trace[1275271133] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:24845; }","duration":"309.734299ms","start":"2026-07-30T01:42:57.075226Z","end":"2026-07-30T01:42:57.384961Z","steps":["trace[1275271133] 'agreement among raft nodes before linearized reading' (duration: 309.697818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.384982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.075141Z","time spent":"309.836861ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"warn","ts":"2026-07-30T01:42:57.385117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.674345ms","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-07-30T01:42:57.385135Z","caller":"traceutil/trace.go:171","msg":"trace[2046404323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24845; }","duration":"391.737416ms","start":"2026-07-30T01:42:56.993393Z","end":"2026-07-30T01:42:57.385131Z","steps":["trace[2046404323] 'agreement among raft nodes before linearized reading' (duration: 391.677785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.385148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:56.993369Z","time spent":"391.775597ms","remote":"127.0.0.1:60522","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-07-30T01:42:57.385343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.552253ms","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-07-30T01:42:57.38542Z","caller":"traceutil/trace.go:171","msg":"trace[86952604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24845; }","duration":"394.683576ms","start":"2026-07-30T01:42:56.990718Z","end":"2026-07-30T01:42:57.385402Z","steps":["trace[86952604] 'agreement among raft nodes before linearized reading' (duration: 394.508752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.385473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:56.990685Z","time spent":"394.771438ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T01:42:57.38557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.778399ms","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-07-30T01:42:57.385599Z","caller":"traceutil/trace.go:171","msg":"trace[590357552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24845; }","duration":"396.849131ms","start":"2026-07-30T01:42:56.988741Z","end":"2026-07-30T01:42:57.38559Z","steps":["trace[590357552] 'agreement among raft nodes before linearized reading' (duration: 396.789849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:57.385614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:56.988709Z","time spent":"396.901411ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-30T01:42:58.413738Z","caller":"traceutil/trace.go:171","msg":"trace[1052873472] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"1.023611305s","start":"2026-07-30T01:42:57.39009Z","end":"2026-07-30T01:42:58.413701Z","steps":["trace[1052873472] 'process raft request' (duration: 1.0233564s)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:58.413752Z","caller":"traceutil/trace.go:171","msg":"trace[1642673026] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"1.023417481s","start":"2026-07-30T01:42:57.390303Z","end":"2026-07-30T01:42:58.413721Z","steps":["trace[1642673026] 'process raft request' (duration: 1.023311969s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:58.413927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.390063Z","time spent":"1.023782338s","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T01:42:58.413944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.390271Z","time spent":"1.023589604s","remote":"127.0.0.1:60522","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-07-30T01:42:58.414137Z","caller":"traceutil/trace.go:171","msg":"trace[1710291495] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"1.0233529s","start":"2026-07-30T01:42:57.390768Z","end":"2026-07-30T01:42:58.414121Z","steps":["trace[1710291495] 'process raft request' (duration: 1.02289116s)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:58.414125Z","caller":"traceutil/trace.go:171","msg":"trace[1827488340] linearizableReadLoop","detail":"{readStateIndex:25655; appliedIndex:25650; }","duration":"972.331772ms","start":"2026-07-30T01:42:57.441763Z","end":"2026-07-30T01:42:58.414095Z","steps":["trace[1827488340] 'read index received' (duration: 24.617311ms)","trace[1827488340] 'applied index is now lower than readState.Index' (duration: 947.712991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:58.414198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.390744Z","time spent":"1.023422962s","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:42:58.41422Z","caller":"traceutil/trace.go:171","msg":"trace[1023005055] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"1.022394971s","start":"2026-07-30T01:42:57.391799Z","end":"2026-07-30T01:42:58.414194Z","steps":["trace[1023005055] 'process raft request' (duration: 1.022153976s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:58.414272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.510316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:58.414302Z","caller":"traceutil/trace.go:171","msg":"trace[1838880220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24850; }","duration":"972.600788ms","start":"2026-07-30T01:42:57.441692Z","end":"2026-07-30T01:42:58.414293Z","steps":["trace[1838880220] 'agreement among raft nodes before linearized reading' (duration: 972.524967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:58.414326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.441667Z","time spent":"972.655589ms","remote":"127.0.0.1:60310","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-07-30T01:42:58.414366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.391778Z","time spent":"1.022513583s","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T01:42:58.914557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":820675137594023941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T01:42:58.969433Z","caller":"traceutil/trace.go:171","msg":"trace[1136651142] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"1.471321879s","start":"2026-07-30T01:42:57.498075Z","end":"2026-07-30T01:42:58.969397Z","steps":["trace[1136651142] 'process raft request' (duration: 1.471043774s)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:58.969532Z","caller":"traceutil/trace.go:171","msg":"trace[508712584] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"1.206444922s","start":"2026-07-30T01:42:57.763051Z","end":"2026-07-30T01:42:58.969496Z","steps":["trace[508712584] 'process raft request' (duration: 1.20631385s)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:58.96964Z","caller":"traceutil/trace.go:171","msg":"trace[176257002] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"1.038202872s","start":"2026-07-30T01:42:57.93136Z","end":"2026-07-30T01:42:58.969563Z","steps":["trace[176257002] 'process raft request' (duration: 1.038048289s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:58.96971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.763031Z","time spent":"1.206587176s","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:42:58.969695Z","caller":"traceutil/trace.go:171","msg":"trace[1556995505] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"948.703021ms","start":"2026-07-30T01:42:58.020941Z","end":"2026-07-30T01:42:58.969644Z","steps":["trace[1556995505] 'process raft request' (duration: 948.505177ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:58.969668Z","caller":"traceutil/trace.go:171","msg":"trace[1395201967] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"1.458444708s","start":"2026-07-30T01:42:57.511016Z","end":"2026-07-30T01:42:58.96946Z","steps":["trace[1395201967] 'process raft request' (duration: 1.458294275s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:58.969687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.49805Z","time spent":"1.471447291s","remote":"127.0.0.1:60522","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-07-30T01:42:58.969926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:58.02091Z","time spent":"948.901016ms","remote":"127.0.0.1:60522","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-07-30T01:42:58.969934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.931331Z","time spent":"1.038440266s","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:42:58.969553Z","caller":"traceutil/trace.go:171","msg":"trace[1440627219] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"947.270173ms","start":"2026-07-30T01:42:58.022266Z","end":"2026-07-30T01:42:58.969536Z","steps":["trace[1440627219] 'process raft request' (duration: 947.220232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:58.970051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:58.022252Z","time spent":"947.752993ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T01:42:58.970282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:57.510985Z","time spent":"1.45905875s","remote":"127.0.0.1:60522","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-07-30T01:42:59.072424Z","caller":"traceutil/trace.go:171","msg":"trace[207742969] linearizableReadLoop","detail":"{readStateIndex:25662; appliedIndex:25655; }","duration":"658.206265ms","start":"2026-07-30T01:42:58.414191Z","end":"2026-07-30T01:42:59.072397Z","steps":["trace[207742969] 'read index received' (duration: 554.917145ms)","trace[207742969] 'applied index is now lower than readState.Index' (duration: 103.28775ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:59.072659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.575141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-30T01:42:59.072675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.715157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:59.072741Z","caller":"traceutil/trace.go:171","msg":"trace[1730573178] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24857; }","duration":"715.744725ms","start":"2026-07-30T01:42:58.356979Z","end":"2026-07-30T01:42:59.072724Z","steps":["trace[1730573178] 'agreement among raft nodes before linearized reading' (duration: 715.50563ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.072752Z","caller":"traceutil/trace.go:171","msg":"trace[614386818] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24857; }","duration":"754.85066ms","start":"2026-07-30T01:42:58.317886Z","end":"2026-07-30T01:42:59.072737Z","steps":["trace[614386818] 'agreement among raft nodes before linearized reading' (duration: 754.691197ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.072511Z","caller":"traceutil/trace.go:171","msg":"trace[98828373] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"232.579009ms","start":"2026-07-30T01:42:58.839886Z","end":"2026-07-30T01:42:59.072465Z","steps":["trace[98828373] 'process raft request' (duration: 231.911136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:59.072792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:58.35695Z","time spent":"715.832346ms","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-30T01:42:59.072796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:58.317863Z","time spent":"754.923221ms","remote":"127.0.0.1:43430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"warn","ts":"2026-07-30T01:42:59.073224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.5876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-30T01:42:59.073236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.639594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-30T01:42:59.073254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.664524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:59.073318Z","caller":"traceutil/trace.go:171","msg":"trace[336695241] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:24857; }","duration":"540.750796ms","start":"2026-07-30T01:42:58.532548Z","end":"2026-07-30T01:42:59.073299Z","steps":["trace[336695241] 'agreement among raft nodes before linearized reading' (duration: 540.556402ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.07332Z","caller":"traceutil/trace.go:171","msg":"trace[1898365959] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24857; }","duration":"563.695952ms","start":"2026-07-30T01:42:58.509601Z","end":"2026-07-30T01:42:59.073297Z","steps":["trace[1898365959] 'agreement among raft nodes before linearized reading' (duration: 563.538809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:59.073374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:58.532533Z","time spent":"540.826718ms","remote":"127.0.0.1:58546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-30T01:42:59.073376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:58.509533Z","time spent":"563.807314ms","remote":"127.0.0.1:37308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"info","ts":"2026-07-30T01:42:59.073414Z","caller":"traceutil/trace.go:171","msg":"trace[1380789992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24857; }","duration":"631.862529ms","start":"2026-07-30T01:42:58.441529Z","end":"2026-07-30T01:42:59.073391Z","steps":["trace[1380789992] 'agreement among raft nodes before linearized reading' (duration: 631.543752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:59.07354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:42:58.441516Z","time spent":"631.997642ms","remote":"127.0.0.1:60298","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-07-30T01:42:59.573409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.333013ms","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-07-30T01:42:59.573505Z","caller":"traceutil/trace.go:171","msg":"trace[1933164789] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24857; }","duration":"196.460835ms","start":"2026-07-30T01:42:59.377016Z","end":"2026-07-30T01:42:59.573477Z","steps":["trace[1933164789] 'range keys from in-memory index tree' (duration: 196.161789ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.573694Z","caller":"traceutil/trace.go:171","msg":"trace[1303207136] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"182.769026ms","start":"2026-07-30T01:42:59.390898Z","end":"2026-07-30T01:42:59.573667Z","steps":["trace[1303207136] 'process raft request' (duration: 179.781546ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.573745Z","caller":"traceutil/trace.go:171","msg":"trace[79921408] linearizableReadLoop","detail":"{readStateIndex:25663; appliedIndex:25662; }","duration":"160.791849ms","start":"2026-07-30T01:42:59.412924Z","end":"2026-07-30T01:42:59.573716Z","steps":["trace[79921408] 'read index received' (duration: 157.775508ms)","trace[79921408] 'applied index is now lower than readState.Index' (duration: 3.015171ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:59.573952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.994033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:59.573979Z","caller":"traceutil/trace.go:171","msg":"trace[1118116864] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:24859; }","duration":"161.082944ms","start":"2026-07-30T01:42:59.412887Z","end":"2026-07-30T01:42:59.57397Z","steps":["trace[1118116864] 'agreement among raft nodes before linearized reading' (duration: 160.992233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:59.574334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.540575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:42:59.574368Z","caller":"traceutil/trace.go:171","msg":"trace[1312893640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24859; }","duration":"132.594786ms","start":"2026-07-30T01:42:59.441761Z","end":"2026-07-30T01:42:59.574356Z","steps":["trace[1312893640] 'agreement among raft nodes before linearized reading' (duration: 132.538225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:42:59.574514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.787231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-30T01:42:59.574631Z","caller":"traceutil/trace.go:171","msg":"trace[250401622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24859; }","duration":"103.887483ms","start":"2026-07-30T01:42:59.470693Z","end":"2026-07-30T01:42:59.57458Z","steps":["trace[250401622] 'agreement among raft nodes before linearized reading' (duration: 103.636338ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.702383Z","caller":"traceutil/trace.go:171","msg":"trace[1979554052] linearizableReadLoop","detail":"{readStateIndex:25665; appliedIndex:25664; }","duration":"123.417909ms","start":"2026-07-30T01:42:59.578937Z","end":"2026-07-30T01:42:59.702355Z","steps":["trace[1979554052] 'read index received' (duration: 122.654794ms)","trace[1979554052] 'applied index is now lower than readState.Index' (duration: 761.825µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:42:59.702636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.674174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18230"} {"level":"info","ts":"2026-07-30T01:42:59.70268Z","caller":"traceutil/trace.go:171","msg":"trace[1532112472] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24860; }","duration":"123.772947ms","start":"2026-07-30T01:42:59.578895Z","end":"2026-07-30T01:42:59.702668Z","steps":["trace[1532112472] 'agreement among raft nodes before linearized reading' (duration: 123.572013ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.702677Z","caller":"traceutil/trace.go:171","msg":"trace[839450136] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"124.303957ms","start":"2026-07-30T01:42:59.57833Z","end":"2026-07-30T01:42:59.702634Z","steps":["trace[839450136] 'process raft request' (duration: 123.241486ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:42:59.702922Z","caller":"traceutil/trace.go:171","msg":"trace[1787281603] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"123.94005ms","start":"2026-07-30T01:42:59.578957Z","end":"2026-07-30T01:42:59.702897Z","steps":["trace[1787281603] 'process raft request' (duration: 123.516182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:01.761209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.794491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-62b765aa-337c-45b4-8103-a9e193b1f4c1\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-30T01:43:01.761297Z","caller":"traceutil/trace.go:171","msg":"trace[1594265578] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-62b765aa-337c-45b4-8103-a9e193b1f4c1; range_end:; response_count:1; response_revision:24930; }","duration":"196.937164ms","start":"2026-07-30T01:43:01.564337Z","end":"2026-07-30T01:43:01.761274Z","steps":["trace[1594265578] 'range keys from in-memory index tree' (duration: 196.547286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:01.761773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.935884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:43:01.761944Z","caller":"traceutil/trace.go:171","msg":"trace[1296162887] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"195.143288ms","start":"2026-07-30T01:43:01.566773Z","end":"2026-07-30T01:43:01.761916Z","steps":["trace[1296162887] 'process raft request' (duration: 90.753065ms)","trace[1296162887] 'compare' (duration: 103.76138ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:01.854602Z","caller":"traceutil/trace.go:171","msg":"trace[2036426180] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"273.765806ms","start":"2026-07-30T01:43:01.58081Z","end":"2026-07-30T01:43:01.854576Z","steps":["trace[2036426180] 'process raft request' (duration: 273.48538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:01.855138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.629741ms","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-07-30T01:43:01.855231Z","caller":"traceutil/trace.go:171","msg":"trace[94415336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24932; }","duration":"147.796835ms","start":"2026-07-30T01:43:01.707414Z","end":"2026-07-30T01:43:01.85521Z","steps":["trace[94415336] 'agreement among raft nodes before linearized reading' (duration: 147.54386ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:01.854522Z","caller":"traceutil/trace.go:171","msg":"trace[357774156] linearizableReadLoop","detail":"{readStateIndex:25738; appliedIndex:25737; }","duration":"146.997969ms","start":"2026-07-30T01:43:01.707484Z","end":"2026-07-30T01:43:01.854482Z","steps":["trace[357774156] 'read index received' (duration: 146.698103ms)","trace[357774156] 'applied index is now lower than readState.Index' (duration: 298.676µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:01.855823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.181673ms","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-07-30T01:43:01.855877Z","caller":"traceutil/trace.go:171","msg":"trace[1015563683] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24932; }","duration":"148.301236ms","start":"2026-07-30T01:43:01.707556Z","end":"2026-07-30T01:43:01.855857Z","steps":["trace[1015563683] 'agreement among raft nodes before linearized reading' (duration: 148.084461ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:03.806656Z","caller":"traceutil/trace.go:171","msg":"trace[1069374276] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"122.94452ms","start":"2026-07-30T01:43:03.683659Z","end":"2026-07-30T01:43:03.806603Z","steps":["trace[1069374276] 'process raft request' (duration: 122.711036ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:07.132346Z","caller":"traceutil/trace.go:171","msg":"trace[908183200] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"131.82648ms","start":"2026-07-30T01:43:07.000459Z","end":"2026-07-30T01:43:07.132285Z","steps":["trace[908183200] 'process raft request' (duration: 131.539955ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:07.132351Z","caller":"traceutil/trace.go:171","msg":"trace[1788734387] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"128.8375ms","start":"2026-07-30T01:43:07.003476Z","end":"2026-07-30T01:43:07.132313Z","steps":["trace[1788734387] 'process raft request' (duration: 128.713078ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:07.13262Z","caller":"traceutil/trace.go:171","msg":"trace[2018715012] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"127.475662ms","start":"2026-07-30T01:43:07.005127Z","end":"2026-07-30T01:43:07.132603Z","steps":["trace[2018715012] 'process raft request' (duration: 127.129585ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:07.13276Z","caller":"traceutil/trace.go:171","msg":"trace[1507356633] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"127.513683ms","start":"2026-07-30T01:43:07.005236Z","end":"2026-07-30T01:43:07.132749Z","steps":["trace[1507356633] 'process raft request' (duration: 127.306849ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:07.268718Z","caller":"traceutil/trace.go:171","msg":"trace[1106234093] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"166.328131ms","start":"2026-07-30T01:43:07.102347Z","end":"2026-07-30T01:43:07.268675Z","steps":["trace[1106234093] 'process raft request' (duration: 162.694688ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:14.644793Z","caller":"traceutil/trace.go:171","msg":"trace[1454236902] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"129.848031ms","start":"2026-07-30T01:43:14.514919Z","end":"2026-07-30T01:43:14.644767Z","steps":["trace[1454236902] 'process raft request' (duration: 129.680587ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:14.645344Z","caller":"traceutil/trace.go:171","msg":"trace[1141098399] linearizableReadLoop","detail":"{readStateIndex:25858; appliedIndex:25858; }","duration":"129.916102ms","start":"2026-07-30T01:43:14.51542Z","end":"2026-07-30T01:43:14.645336Z","steps":["trace[1141098399] 'read index received' (duration: 129.912932ms)","trace[1141098399] 'applied index is now lower than readState.Index' (duration: 2.72µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:14.645471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.026114ms","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-07-30T01:43:14.645492Z","caller":"traceutil/trace.go:171","msg":"trace[1491642642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25050; }","duration":"130.078715ms","start":"2026-07-30T01:43:14.515405Z","end":"2026-07-30T01:43:14.645484Z","steps":["trace[1491642642] 'agreement among raft nodes before linearized reading' (duration: 129.965803ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:14.800249Z","caller":"traceutil/trace.go:171","msg":"trace[990633679] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"145.666972ms","start":"2026-07-30T01:43:14.654552Z","end":"2026-07-30T01:43:14.800219Z","steps":["trace[990633679] 'process raft request' (duration: 145.616381ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:14.800599Z","caller":"traceutil/trace.go:171","msg":"trace[1897607994] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"199.746123ms","start":"2026-07-30T01:43:14.600843Z","end":"2026-07-30T01:43:14.800589Z","steps":["trace[1897607994] 'process raft request' (duration: 172.007538ms)","trace[1897607994] 'compare' (duration: 26.870916ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:14.800681Z","caller":"traceutil/trace.go:171","msg":"trace[259756142] linearizableReadLoop","detail":"{readStateIndex:25859; appliedIndex:25858; }","duration":"155.315359ms","start":"2026-07-30T01:43:14.645357Z","end":"2026-07-30T01:43:14.800673Z","steps":["trace[259756142] 'read index received' (duration: 127.468493ms)","trace[259756142] 'applied index is now lower than readState.Index' (duration: 27.845956ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:14.800808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.893564ms","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-07-30T01:43:14.800834Z","caller":"traceutil/trace.go:171","msg":"trace[1174583296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25052; }","duration":"284.986415ms","start":"2026-07-30T01:43:14.515836Z","end":"2026-07-30T01:43:14.800822Z","steps":["trace[1174583296] 'agreement among raft nodes before linearized reading' (duration: 284.858743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:14.801093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.083327ms","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-07-30T01:43:14.801112Z","caller":"traceutil/trace.go:171","msg":"trace[444530801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25052; }","duration":"281.122217ms","start":"2026-07-30T01:43:14.519982Z","end":"2026-07-30T01:43:14.801105Z","steps":["trace[444530801] 'agreement among raft nodes before linearized reading' (duration: 281.013565ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:14.96644Z","caller":"traceutil/trace.go:171","msg":"trace[1099135015] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"160.836252ms","start":"2026-07-30T01:43:14.805567Z","end":"2026-07-30T01:43:14.966403Z","steps":["trace[1099135015] 'process raft request' (duration: 104.743791ms)","trace[1099135015] 'compare' (duration: 55.873706ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:15.236867Z","caller":"traceutil/trace.go:171","msg":"trace[1311832409] linearizableReadLoop","detail":"{readStateIndex:25863; appliedIndex:25861; }","duration":"187.834699ms","start":"2026-07-30T01:43:15.049009Z","end":"2026-07-30T01:43:15.236843Z","steps":["trace[1311832409] 'read index received' (duration: 134.314801ms)","trace[1311832409] 'applied index is now lower than readState.Index' (duration: 53.518598ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:15.236906Z","caller":"traceutil/trace.go:171","msg":"trace[1406342584] transaction","detail":"{read_only:false; response_revision:25054; number_of_response:1; }","duration":"429.242208ms","start":"2026-07-30T01:43:14.807606Z","end":"2026-07-30T01:43:15.236848Z","steps":["trace[1406342584] 'process raft request' (duration: 375.70105ms)","trace[1406342584] 'compare' (duration: 53.208082ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:15.237048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.028864ms","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-07-30T01:43:15.237078Z","caller":"traceutil/trace.go:171","msg":"trace[265356354] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25055; }","duration":"188.120616ms","start":"2026-07-30T01:43:15.048948Z","end":"2026-07-30T01:43:15.237069Z","steps":["trace[265356354] 'agreement among raft nodes before linearized reading' (duration: 187.972493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:15.237091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:14.807578Z","time spent":"429.412862ms","remote":"127.0.0.1:47666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418457,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:43:15.237171Z","caller":"traceutil/trace.go:171","msg":"trace[900295309] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"429.234278ms","start":"2026-07-30T01:43:14.80791Z","end":"2026-07-30T01:43:15.237145Z","steps":["trace[900295309] 'process raft request' (duration: 428.877691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:15.237328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:14.807905Z","time spent":"429.32915ms","remote":"127.0.0.1:60522","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-07-30T01:43:15.477745Z","caller":"traceutil/trace.go:171","msg":"trace[1878773776] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"313.687799ms","start":"2026-07-30T01:43:15.164038Z","end":"2026-07-30T01:43:15.477726Z","steps":["trace[1878773776] 'process raft request' (duration: 313.620597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:15.477881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:15.164004Z","time spent":"313.819661ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:43:15.478026Z","caller":"traceutil/trace.go:171","msg":"trace[1418253472] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"409.877224ms","start":"2026-07-30T01:43:15.068143Z","end":"2026-07-30T01:43:15.47802Z","steps":["trace[1418253472] 'process raft request' (duration: 394.812669ms)","trace[1418253472] 'compare' (duration: 14.593026ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:15.47807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:15.068124Z","time spent":"409.922215ms","remote":"127.0.0.1:60522","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-07-30T01:43:15.478155Z","caller":"traceutil/trace.go:171","msg":"trace[474259385] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"311.997734ms","start":"2026-07-30T01:43:15.166113Z","end":"2026-07-30T01:43:15.478111Z","steps":["trace[474259385] 'process raft request' (duration: 311.582066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:15.478323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:15.166099Z","time spent":"312.139157ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:43:15.478498Z","caller":"traceutil/trace.go:171","msg":"trace[261308970] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"309.318051ms","start":"2026-07-30T01:43:15.169173Z","end":"2026-07-30T01:43:15.478491Z","steps":["trace[261308970] 'process raft request' (duration: 308.84819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:15.478537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:15.169159Z","time spent":"309.359441ms","remote":"127.0.0.1:60522","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-07-30T01:43:15.478645Z","caller":"traceutil/trace.go:171","msg":"trace[1746298264] linearizableReadLoop","detail":"{readStateIndex:25868; appliedIndex:25863; }","duration":"237.584572ms","start":"2026-07-30T01:43:15.241041Z","end":"2026-07-30T01:43:15.478625Z","steps":["trace[1746298264] 'read index received' (duration: 221.896572ms)","trace[1746298264] 'applied index is now lower than readState.Index' (duration: 15.6866ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:15.478678Z","caller":"traceutil/trace.go:171","msg":"trace[1713376491] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"308.031773ms","start":"2026-07-30T01:43:15.170637Z","end":"2026-07-30T01:43:15.478668Z","steps":["trace[1713376491] 'process raft request' (duration: 307.826709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:15.47872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:15.170607Z","time spent":"308.090955ms","remote":"127.0.0.1:60522","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-07-30T01:43:15.478825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.784745ms","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-07-30T01:43:15.47886Z","caller":"traceutil/trace.go:171","msg":"trace[1198490233] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25060; }","duration":"237.874877ms","start":"2026-07-30T01:43:15.240975Z","end":"2026-07-30T01:43:15.47885Z","steps":["trace[1198490233] 'agreement among raft nodes before linearized reading' (duration: 237.724044ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:15.480252Z","caller":"traceutil/trace.go:171","msg":"trace[354690516] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"180.433099ms","start":"2026-07-30T01:43:15.299795Z","end":"2026-07-30T01:43:15.480228Z","steps":["trace[354690516] 'process raft request' (duration: 180.200844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:15.480468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.06474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-07-30T01:43:15.480493Z","caller":"traceutil/trace.go:171","msg":"trace[1750040908] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25061; }","duration":"147.127402ms","start":"2026-07-30T01:43:15.333355Z","end":"2026-07-30T01:43:15.480482Z","steps":["trace[1750040908] 'agreement among raft nodes before linearized reading' (duration: 146.876507ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:16.805169Z","caller":"traceutil/trace.go:171","msg":"trace[113092279] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"143.277233ms","start":"2026-07-30T01:43:16.661868Z","end":"2026-07-30T01:43:16.805145Z","steps":["trace[113092279] 'process raft request' (duration: 143.064979ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.592597Z","caller":"traceutil/trace.go:171","msg":"trace[1453108142] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"256.824363ms","start":"2026-07-30T01:43:33.335718Z","end":"2026-07-30T01:43:33.592542Z","steps":["trace[1453108142] 'process raft request' (duration: 256.430325ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.592686Z","caller":"traceutil/trace.go:171","msg":"trace[1825297206] linearizableReadLoop","detail":"{readStateIndex:26017; appliedIndex:26016; }","duration":"211.314518ms","start":"2026-07-30T01:43:33.381109Z","end":"2026-07-30T01:43:33.592424Z","steps":["trace[1825297206] 'read index received' (duration: 211.117614ms)","trace[1825297206] 'applied index is now lower than readState.Index' (duration: 195.814µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:33.592804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.644734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:33.592913Z","caller":"traceutil/trace.go:171","msg":"trace[413558460] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25205; }","duration":"211.848958ms","start":"2026-07-30T01:43:33.381036Z","end":"2026-07-30T01:43:33.592885Z","steps":["trace[413558460] 'agreement among raft nodes before linearized reading' (duration: 211.622484ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.790393Z","caller":"traceutil/trace.go:171","msg":"trace[294911511] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"240.849718ms","start":"2026-07-30T01:43:33.54951Z","end":"2026-07-30T01:43:33.790359Z","steps":["trace[294911511] 'process raft request' (duration: 240.774627ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.790411Z","caller":"traceutil/trace.go:171","msg":"trace[229437916] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"243.605534ms","start":"2026-07-30T01:43:33.546762Z","end":"2026-07-30T01:43:33.790368Z","steps":["trace[229437916] 'process raft request' (duration: 243.298528ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.790446Z","caller":"traceutil/trace.go:171","msg":"trace[419601070] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"241.46177ms","start":"2026-07-30T01:43:33.548949Z","end":"2026-07-30T01:43:33.790411Z","steps":["trace[419601070] 'process raft request' (duration: 241.291317ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.790733Z","caller":"traceutil/trace.go:171","msg":"trace[733412091] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"240.422919ms","start":"2026-07-30T01:43:33.550259Z","end":"2026-07-30T01:43:33.790682Z","steps":["trace[733412091] 'process raft request' (duration: 240.059861ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.790818Z","caller":"traceutil/trace.go:171","msg":"trace[1798706504] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"238.51325ms","start":"2026-07-30T01:43:33.552291Z","end":"2026-07-30T01:43:33.790805Z","steps":["trace[1798706504] 'process raft request' (duration: 238.414618ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.790794Z","caller":"traceutil/trace.go:171","msg":"trace[1526320752] linearizableReadLoop","detail":"{readStateIndex:26023; appliedIndex:26017; }","duration":"197.985755ms","start":"2026-07-30T01:43:33.592785Z","end":"2026-07-30T01:43:33.790771Z","steps":["trace[1526320752] 'read index received' (duration: 197.261591ms)","trace[1526320752] 'applied index is now lower than readState.Index' (duration: 722.584µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:33.79096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.702313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:33.791005Z","caller":"traceutil/trace.go:171","msg":"trace[1794681498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25211; }","duration":"350.814495ms","start":"2026-07-30T01:43:33.440177Z","end":"2026-07-30T01:43:33.790991Z","steps":["trace[1794681498] 'agreement among raft nodes before linearized reading' (duration: 350.685682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:33.79104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:43:33.440152Z","time spent":"350.879456ms","remote":"127.0.0.1:60298","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-07-30T01:43:33.791025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.267251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:33.791135Z","caller":"traceutil/trace.go:171","msg":"trace[83674133] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25211; }","duration":"283.457795ms","start":"2026-07-30T01:43:33.507656Z","end":"2026-07-30T01:43:33.791114Z","steps":["trace[83674133] 'agreement among raft nodes before linearized reading' (duration: 283.239161ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:33.791726Z","caller":"traceutil/trace.go:171","msg":"trace[1216343588] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"238.646754ms","start":"2026-07-30T01:43:33.552196Z","end":"2026-07-30T01:43:33.790843Z","steps":["trace[1216343588] 'process raft request' (duration: 238.376588ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:34.335221Z","caller":"traceutil/trace.go:171","msg":"trace[855269688] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"105.169269ms","start":"2026-07-30T01:43:34.230023Z","end":"2026-07-30T01:43:34.335192Z","steps":["trace[855269688] 'process raft request' (duration: 104.976255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:34.612422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.047198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:34.612525Z","caller":"traceutil/trace.go:171","msg":"trace[125393333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25214; }","duration":"169.224542ms","start":"2026-07-30T01:43:34.443276Z","end":"2026-07-30T01:43:34.612501Z","steps":["trace[125393333] 'range keys from in-memory index tree' (duration: 168.846885ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:35.06479Z","caller":"traceutil/trace.go:171","msg":"trace[1680219704] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"160.144367ms","start":"2026-07-30T01:43:34.904618Z","end":"2026-07-30T01:43:35.064762Z","steps":["trace[1680219704] 'process raft request' (duration: 159.953593ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:35.13911Z","caller":"traceutil/trace.go:171","msg":"trace[1314809696] linearizableReadLoop","detail":"{readStateIndex:26030; appliedIndex:26027; }","duration":"206.096621ms","start":"2026-07-30T01:43:34.932988Z","end":"2026-07-30T01:43:35.139084Z","steps":["trace[1314809696] 'read index received' (duration: 131.595426ms)","trace[1314809696] 'applied index is now lower than readState.Index' (duration: 74.499845ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:35.139207Z","caller":"traceutil/trace.go:171","msg":"trace[105491747] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"233.073299ms","start":"2026-07-30T01:43:34.906073Z","end":"2026-07-30T01:43:35.139147Z","steps":["trace[105491747] 'process raft request' (duration: 232.843615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:35.139286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.282846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:35.139313Z","caller":"traceutil/trace.go:171","msg":"trace[69042716] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25218; }","duration":"206.379877ms","start":"2026-07-30T01:43:34.932927Z","end":"2026-07-30T01:43:35.139307Z","steps":["trace[69042716] 'agreement among raft nodes before linearized reading' (duration: 206.280585ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:35.139204Z","caller":"traceutil/trace.go:171","msg":"trace[1070968297] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"232.143511ms","start":"2026-07-30T01:43:34.907012Z","end":"2026-07-30T01:43:35.139156Z","steps":["trace[1070968297] 'process raft request' (duration: 232.043119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:35.13956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.153781ms","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-07-30T01:43:35.139654Z","caller":"traceutil/trace.go:171","msg":"trace[983179450] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25218; }","duration":"143.322914ms","start":"2026-07-30T01:43:34.996308Z","end":"2026-07-30T01:43:35.139631Z","steps":["trace[983179450] 'agreement among raft nodes before linearized reading' (duration: 143.13795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:35.35146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.249774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:43:35.351615Z","caller":"traceutil/trace.go:171","msg":"trace[1066897063] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"207.196124ms","start":"2026-07-30T01:43:35.144386Z","end":"2026-07-30T01:43:35.351582Z","steps":["trace[1066897063] 'process raft request' (duration: 62.699485ms)","trace[1066897063] 'compare' (duration: 143.732662ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:35.900839Z","caller":"traceutil/trace.go:171","msg":"trace[810322026] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"104.269411ms","start":"2026-07-30T01:43:35.796548Z","end":"2026-07-30T01:43:35.900817Z","steps":["trace[810322026] 'process raft request' (duration: 104.144469ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:35.986444Z","caller":"traceutil/trace.go:171","msg":"trace[1918550328] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"189.382361ms","start":"2026-07-30T01:43:35.797036Z","end":"2026-07-30T01:43:35.986418Z","steps":["trace[1918550328] 'process raft request' (duration: 189.287369ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:35.986514Z","caller":"traceutil/trace.go:171","msg":"trace[1849518190] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"189.85024ms","start":"2026-07-30T01:43:35.796607Z","end":"2026-07-30T01:43:35.986457Z","steps":["trace[1849518190] 'process raft request' (duration: 175.437127ms)","trace[1849518190] 'compare' (duration: 14.131768ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:35.986896Z","caller":"traceutil/trace.go:171","msg":"trace[1486469488] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"189.256648ms","start":"2026-07-30T01:43:35.797577Z","end":"2026-07-30T01:43:35.986834Z","steps":["trace[1486469488] 'process raft request' (duration: 188.801419ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:35.987088Z","caller":"traceutil/trace.go:171","msg":"trace[1797407964] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"189.477942ms","start":"2026-07-30T01:43:35.797585Z","end":"2026-07-30T01:43:35.987063Z","steps":["trace[1797407964] 'process raft request' (duration: 189.133436ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:35.987083Z","caller":"traceutil/trace.go:171","msg":"trace[295541825] linearizableReadLoop","detail":"{readStateIndex:26038; appliedIndex:26033; }","duration":"111.66746ms","start":"2026-07-30T01:43:35.875387Z","end":"2026-07-30T01:43:35.987055Z","steps":["trace[295541825] 'read index received' (duration: 25.935418ms)","trace[295541825] 'applied index is now lower than readState.Index' (duration: 85.730802ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:35.98724Z","caller":"traceutil/trace.go:171","msg":"trace[275939487] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"188.685018ms","start":"2026-07-30T01:43:35.798533Z","end":"2026-07-30T01:43:35.987218Z","steps":["trace[275939487] 'process raft request' (duration: 188.442482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:35.988762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.353025ms","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-07-30T01:43:35.988838Z","caller":"traceutil/trace.go:171","msg":"trace[1197342118] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25226; }","duration":"113.490348ms","start":"2026-07-30T01:43:35.87533Z","end":"2026-07-30T01:43:35.98882Z","steps":["trace[1197342118] 'agreement among raft nodes before linearized reading' (duration: 111.809744ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:36.253171Z","caller":"traceutil/trace.go:171","msg":"trace[1006344221] linearizableReadLoop","detail":"{readStateIndex:26039; appliedIndex:26038; }","duration":"183.397649ms","start":"2026-07-30T01:43:36.069746Z","end":"2026-07-30T01:43:36.253144Z","steps":["trace[1006344221] 'read index received' (duration: 97.33031ms)","trace[1006344221] 'applied index is now lower than readState.Index' (duration: 86.065949ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:36.253262Z","caller":"traceutil/trace.go:171","msg":"trace[701750422] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"192.010726ms","start":"2026-07-30T01:43:36.061201Z","end":"2026-07-30T01:43:36.253212Z","steps":["trace[701750422] 'process raft request' (duration: 105.860273ms)","trace[701750422] 'compare' (duration: 85.675122ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:36.253328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.564073ms","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-07-30T01:43:36.253359Z","caller":"traceutil/trace.go:171","msg":"trace[1074984548] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25227; }","duration":"183.635654ms","start":"2026-07-30T01:43:36.069713Z","end":"2026-07-30T01:43:36.253348Z","steps":["trace[1074984548] 'agreement among raft nodes before linearized reading' (duration: 183.559993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:36.253516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.633875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-30T01:43:36.253871Z","caller":"traceutil/trace.go:171","msg":"trace[1631865591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25227; }","duration":"124.058613ms","start":"2026-07-30T01:43:36.12979Z","end":"2026-07-30T01:43:36.253849Z","steps":["trace[1631865591] 'agreement among raft nodes before linearized reading' (duration: 123.575064ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:37.49446Z","caller":"traceutil/trace.go:171","msg":"trace[984209055] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"131.487014ms","start":"2026-07-30T01:43:37.362942Z","end":"2026-07-30T01:43:37.494429Z","steps":["trace[984209055] 'process raft request' (duration: 131.195937ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:48.164524Z","caller":"traceutil/trace.go:171","msg":"trace[1523779853] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"130.87089ms","start":"2026-07-30T01:43:48.033625Z","end":"2026-07-30T01:43:48.164496Z","steps":["trace[1523779853] 'process raft request' (duration: 63.209334ms)","trace[1523779853] 'compare' (duration: 67.452662ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:48.164885Z","caller":"traceutil/trace.go:171","msg":"trace[1458164151] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"130.693107ms","start":"2026-07-30T01:43:48.034159Z","end":"2026-07-30T01:43:48.164852Z","steps":["trace[1458164151] 'process raft request' (duration: 130.288898ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:48.165231Z","caller":"traceutil/trace.go:171","msg":"trace[1303475536] transaction","detail":"{read_only:false; response_revision:25311; number_of_response:1; }","duration":"129.917773ms","start":"2026-07-30T01:43:48.035297Z","end":"2026-07-30T01:43:48.165215Z","steps":["trace[1303475536] 'process raft request' (duration: 129.822711ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:48.165297Z","caller":"traceutil/trace.go:171","msg":"trace[384697684] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"130.968653ms","start":"2026-07-30T01:43:48.034316Z","end":"2026-07-30T01:43:48.165285Z","steps":["trace[384697684] 'process raft request' (duration: 130.415352ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:48.165309Z","caller":"traceutil/trace.go:171","msg":"trace[1317783211] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"129.186077ms","start":"2026-07-30T01:43:48.036104Z","end":"2026-07-30T01:43:48.165291Z","steps":["trace[1317783211] 'process raft request' (duration: 129.071835ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:48.451645Z","caller":"traceutil/trace.go:171","msg":"trace[536279833] linearizableReadLoop","detail":"{readStateIndex:26128; appliedIndex:26127; }","duration":"115.030339ms","start":"2026-07-30T01:43:48.336587Z","end":"2026-07-30T01:43:48.451618Z","steps":["trace[536279833] 'read index received' (duration: 97.159546ms)","trace[536279833] 'applied index is now lower than readState.Index' (duration: 17.869453ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:48.451783Z","caller":"traceutil/trace.go:171","msg":"trace[1272018078] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"148.491939ms","start":"2026-07-30T01:43:48.303279Z","end":"2026-07-30T01:43:48.451771Z","steps":["trace[1272018078] 'process raft request' (duration: 130.488664ms)","trace[1272018078] 'compare' (duration: 17.645758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:48.452016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.414517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T01:43:48.452076Z","caller":"traceutil/trace.go:171","msg":"trace[618297494] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25313; }","duration":"115.535649ms","start":"2026-07-30T01:43:48.336527Z","end":"2026-07-30T01:43:48.452063Z","steps":["trace[618297494] 'agreement among raft nodes before linearized reading' (duration: 115.418727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:53.459438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.881668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-30T01:43:53.459832Z","caller":"traceutil/trace.go:171","msg":"trace[453830272] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"185.237406ms","start":"2026-07-30T01:43:53.27456Z","end":"2026-07-30T01:43:53.459797Z","steps":["trace[453830272] 'process raft request' (duration: 65.864928ms)","trace[453830272] 'compare' (duration: 118.608202ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:58.805758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.423499ms","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-07-30T01:43:58.805839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.742133ms","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-07-30T01:43:58.805893Z","caller":"traceutil/trace.go:171","msg":"trace[584709110] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25398; }","duration":"209.582702ms","start":"2026-07-30T01:43:58.596285Z","end":"2026-07-30T01:43:58.805868Z","steps":["trace[584709110] 'range keys from in-memory index tree' (duration: 209.248586ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:58.805922Z","caller":"traceutil/trace.go:171","msg":"trace[1323239388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25398; }","duration":"231.942527ms","start":"2026-07-30T01:43:58.57396Z","end":"2026-07-30T01:43:58.805902Z","steps":["trace[1323239388] 'range keys from in-memory index tree' (duration: 231.5538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:58.806005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.853524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-30T01:43:58.806131Z","caller":"traceutil/trace.go:171","msg":"trace[23713928] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:25398; }","duration":"230.199621ms","start":"2026-07-30T01:43:58.575909Z","end":"2026-07-30T01:43:58.806109Z","steps":["trace[23713928] 'count revisions from in-memory index tree' (duration: 229.798843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:59.11948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.273696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:59.119595Z","caller":"traceutil/trace.go:171","msg":"trace[1274021309] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25400; }","duration":"179.421709ms","start":"2026-07-30T01:43:58.94015Z","end":"2026-07-30T01:43:59.119571Z","steps":["trace[1274021309] 'count revisions from in-memory index tree' (duration: 179.164213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:43:59.119491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.174714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:59.119805Z","caller":"traceutil/trace.go:171","msg":"trace[735381311] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:25400; }","duration":"147.4935ms","start":"2026-07-30T01:43:58.972237Z","end":"2026-07-30T01:43:59.11973Z","steps":["trace[735381311] 'count revisions from in-memory index tree' (duration: 146.951848ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:59.499442Z","caller":"traceutil/trace.go:171","msg":"trace[1716618612] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"194.73947ms","start":"2026-07-30T01:43:59.304674Z","end":"2026-07-30T01:43:59.499413Z","steps":["trace[1716618612] 'process raft request' (duration: 194.518426ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:43:59.906832Z","caller":"traceutil/trace.go:171","msg":"trace[1711209245] linearizableReadLoop","detail":"{readStateIndex:26221; appliedIndex:26220; }","duration":"216.477812ms","start":"2026-07-30T01:43:59.690327Z","end":"2026-07-30T01:43:59.906804Z","steps":["trace[1711209245] 'read index received' (duration: 130.370271ms)","trace[1711209245] 'applied index is now lower than readState.Index' (duration: 86.105911ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:43:59.907019Z","caller":"traceutil/trace.go:171","msg":"trace[621472270] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"275.270398ms","start":"2026-07-30T01:43:59.631717Z","end":"2026-07-30T01:43:59.906988Z","steps":["trace[621472270] 'process raft request' (duration: 189.079205ms)","trace[621472270] 'compare' (duration: 85.840696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T01:43:59.907188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.829309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:43:59.907321Z","caller":"traceutil/trace.go:171","msg":"trace[1685093516] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25403; }","duration":"217.011923ms","start":"2026-07-30T01:43:59.690279Z","end":"2026-07-30T01:43:59.907291Z","steps":["trace[1685093516] 'agreement among raft nodes before linearized reading' (duration: 216.770558ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:00.045044Z","caller":"traceutil/trace.go:171","msg":"trace[248238428] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"131.141466ms","start":"2026-07-30T01:43:59.913854Z","end":"2026-07-30T01:44:00.044996Z","steps":["trace[248238428] 'process raft request' (duration: 127.323009ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:00.498371Z","caller":"traceutil/trace.go:171","msg":"trace[809873018] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"283.187809ms","start":"2026-07-30T01:44:00.215149Z","end":"2026-07-30T01:44:00.498336Z","steps":["trace[809873018] 'process raft request' (duration: 282.952724ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:00.561418Z","caller":"traceutil/trace.go:171","msg":"trace[1411474138] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"344.773131ms","start":"2026-07-30T01:44:00.216596Z","end":"2026-07-30T01:44:00.561369Z","steps":["trace[1411474138] 'process raft request' (duration: 344.439835ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:00.561641Z","caller":"traceutil/trace.go:171","msg":"trace[186935652] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"341.738799ms","start":"2026-07-30T01:44:00.21988Z","end":"2026-07-30T01:44:00.561619Z","steps":["trace[186935652] 'process raft request' (duration: 341.408193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:44:00.561716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:44:00.21656Z","time spent":"344.981096ms","remote":"127.0.0.1:60522","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-07-30T01:44:00.561861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:44:00.219865Z","time spent":"341.853571ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:44:00.561423Z","caller":"traceutil/trace.go:171","msg":"trace[257033405] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"341.853002ms","start":"2026-07-30T01:44:00.219513Z","end":"2026-07-30T01:44:00.561366Z","steps":["trace[257033405] 'process raft request' (duration: 341.72742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:44:00.562166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:44:00.219446Z","time spent":"342.651318ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:44:00.562278Z","caller":"traceutil/trace.go:171","msg":"trace[2053435304] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26225; }","duration":"119.590133ms","start":"2026-07-30T01:44:00.442649Z","end":"2026-07-30T01:44:00.562239Z","steps":["trace[2053435304] 'read index received' (duration: 55.856706ms)","trace[2053435304] 'applied index is now lower than readState.Index' (duration: 63.726846ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:44:00.562304Z","caller":"traceutil/trace.go:171","msg":"trace[1084726107] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"275.548803ms","start":"2026-07-30T01:44:00.286724Z","end":"2026-07-30T01:44:00.562273Z","steps":["trace[1084726107] 'process raft request' (duration: 275.329929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:44:00.562411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.768966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:44:00.562304Z","caller":"traceutil/trace.go:171","msg":"trace[401218926] transaction","detail":"{read_only:false; response_revision:25411; number_of_response:1; }","duration":"339.398212ms","start":"2026-07-30T01:44:00.222856Z","end":"2026-07-30T01:44:00.562254Z","steps":["trace[401218926] 'process raft request' (duration: 338.673927ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:00.562468Z","caller":"traceutil/trace.go:171","msg":"trace[271778381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25412; }","duration":"119.874318ms","start":"2026-07-30T01:44:00.442581Z","end":"2026-07-30T01:44:00.562455Z","steps":["trace[271778381] 'agreement among raft nodes before linearized reading' (duration: 119.752275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:44:00.562575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T01:44:00.222842Z","time spent":"339.654187ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T01:44:48.130931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22976} {"level":"info","ts":"2026-07-30T01:44:48.276974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22976,"took":"141.493688ms","hash":14180264,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":35307520,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-30T01:44:48.277037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":14180264,"revision":22976,"compact-revision":20019} {"level":"info","ts":"2026-07-30T01:44:50.878861Z","caller":"traceutil/trace.go:171","msg":"trace[1993036892] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"104.183309ms","start":"2026-07-30T01:44:50.774648Z","end":"2026-07-30T01:44:50.878831Z","steps":["trace[1993036892] 'process raft request' (duration: 104.071086ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:50.878871Z","caller":"traceutil/trace.go:171","msg":"trace[1336118014] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"104.458735ms","start":"2026-07-30T01:44:50.774368Z","end":"2026-07-30T01:44:50.878826Z","steps":["trace[1336118014] 'process raft request' (duration: 104.182509ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:50.879217Z","caller":"traceutil/trace.go:171","msg":"trace[19965907] linearizableReadLoop","detail":"{readStateIndex:26784; appliedIndex:26781; }","duration":"103.958424ms","start":"2026-07-30T01:44:50.77524Z","end":"2026-07-30T01:44:50.879198Z","steps":["trace[19965907] 'read index received' (duration: 57.471079ms)","trace[19965907] 'applied index is now lower than readState.Index' (duration: 46.485545ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T01:44:50.879312Z","caller":"traceutil/trace.go:171","msg":"trace[1395041589] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"102.174999ms","start":"2026-07-30T01:44:50.777126Z","end":"2026-07-30T01:44:50.879301Z","steps":["trace[1395041589] 'process raft request' (duration: 101.977655ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:44:50.879316Z","caller":"traceutil/trace.go:171","msg":"trace[2026045208] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"103.129507ms","start":"2026-07-30T01:44:50.776153Z","end":"2026-07-30T01:44:50.879283Z","steps":["trace[2026045208] 'process raft request' (duration: 102.629236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T01:44:50.879379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.117247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-30T01:44:50.879422Z","caller":"traceutil/trace.go:171","msg":"trace[652172660] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25956; }","duration":"104.205199ms","start":"2026-07-30T01:44:50.775204Z","end":"2026-07-30T01:44:50.879409Z","steps":["trace[652172660] 'agreement among raft nodes before linearized reading' (duration: 104.112097ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:45:14.710788Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069aa.wal"} {"level":"info","ts":"2026-07-30T01:47:41.075715Z","caller":"traceutil/trace.go:171","msg":"trace[357589010] transaction","detail":"{read_only:false; response_revision:27673; number_of_response:1; }","duration":"104.391156ms","start":"2026-07-30T01:47:40.971287Z","end":"2026-07-30T01:47:41.075678Z","steps":["trace[357589010] 'process raft request' (duration: 94.701349ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T01:49:48.140136Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25932} {"level":"info","ts":"2026-07-30T01:49:48.292743Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25932,"took":"147.359212ms","hash":1235159183,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":34578432,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-30T01:49:48.292828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1235159183,"revision":25932,"compact-revision":22976} {"level":"info","ts":"2026-07-30T01:50:46.370531Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T01:50:46.376442Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-30T01:50:46.376565Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-30T01:54:48.14844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28626} {"level":"info","ts":"2026-07-30T01:54:48.292546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28626,"took":"139.048062ms","hash":409294260,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":30334976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-30T01:54:48.292634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":409294260,"revision":28626,"compact-revision":25932} {"level":"info","ts":"2026-07-30T01:59:48.157182Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30845} {"level":"info","ts":"2026-07-30T01:59:48.276906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30845,"took":"114.190968ms","hash":2435008511,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-30T01:59:48.276986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2435008511,"revision":30845,"compact-revision":28626} {"level":"info","ts":"2026-07-30T02:04:48.163713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33105} {"level":"info","ts":"2026-07-30T02:04:48.326389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33105,"took":"158.42782ms","hash":4248575351,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":32612352,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-30T02:04:48.326546Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4248575351,"revision":33105,"compact-revision":30845} {"level":"info","ts":"2026-07-30T02:09:48.170374Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35467} {"level":"info","ts":"2026-07-30T02:09:48.321391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35467,"took":"145.644238ms","hash":2182878545,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":31866880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-30T02:09:48.321449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2182878545,"revision":35467,"compact-revision":33105} {"level":"info","ts":"2026-07-30T02:12:05.063392Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5a9e84e0e0a90b63","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T02:12:05.068095Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-30T02:12:05.068331Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-30T02:14:48.176683Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37723} {"level":"info","ts":"2026-07-30T02:14:48.303373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37723,"took":"120.467706ms","hash":3308016848,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":32145408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-30T02:14:48.303434Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3308016848,"revision":37723,"compact-revision":35467}