{"level":"warn","ts":"2026-10-03T02:34:32.686931Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T02:34:32.687079Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.73: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.73:2380","--initial-cluster=instance=https://162.253.55.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.73: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-10-03T02:34:32.687175Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-03T02:34:32.687213Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.73:2380"]} {"level":"info","ts":"2026-10-03T02:34:32.687255Z","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-10-03T02:34:32.688005Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"]} {"level":"info","ts":"2026-10-03T02:34:32.688129Z","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.73:2380"],"listen-peer-urls":["https://162.253.55.73:2380"],"advertise-client-urls":["https://162.253.55.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.73: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-10-03T02:34:32.690768Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.367894ms"} {"level":"info","ts":"2026-10-03T02:34:32.696756Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c43c9f84c6588d4","cluster-id":"49c90045dbd991f3"} {"level":"info","ts":"2026-10-03T02:34:32.696857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=()"} {"level":"info","ts":"2026-10-03T02:34:32.696895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became follower at term 0"} {"level":"info","ts":"2026-10-03T02:34:32.696922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c43c9f84c6588d4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-03T02:34:32.696954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became follower at term 1"} {"level":"info","ts":"2026-10-03T02:34:32.697008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=(5495458038592932052)"} {"level":"warn","ts":"2026-10-03T02:34:32.702788Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-03T02:34:32.704639Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-03T02:34:32.706144Z","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-10-03T02:34:32.70859Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c43c9f84c6588d4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-03T02:34:32.708885Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c43c9f84c6588d4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-03T02:34:32.708982Z","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-10-03T02:34:32.709243Z","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-10-03T02:34:32.709268Z","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-10-03T02:34:32.711027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 switched to configuration voters=(5495458038592932052)"} {"level":"info","ts":"2026-10-03T02:34:32.71131Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49c90045dbd991f3","local-member-id":"4c43c9f84c6588d4","added-peer-id":"4c43c9f84c6588d4","added-peer-peer-urls":["https://162.253.55.73:2380"]} {"level":"info","ts":"2026-10-03T02:34:32.717061Z","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-10-03T02:34:32.717156Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.73:2380"} {"level":"info","ts":"2026-10-03T02:34:32.717239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.73:2380"} {"level":"info","ts":"2026-10-03T02:34:32.717456Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c43c9f84c6588d4","initial-advertise-peer-urls":["https://162.253.55.73:2380"],"listen-peer-urls":["https://162.253.55.73:2380"],"advertise-client-urls":["https://162.253.55.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-03T02:34:32.717593Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-03T02:34:33.298107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 is starting a new election at term 1"} {"level":"info","ts":"2026-10-03T02:34:33.298179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-03T02:34:33.298218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 received MsgPreVoteResp from 4c43c9f84c6588d4 at term 1"} {"level":"info","ts":"2026-10-03T02:34:33.298237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became candidate at term 2"} {"level":"info","ts":"2026-10-03T02:34:33.298248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 received MsgVoteResp from 4c43c9f84c6588d4 at term 2"} {"level":"info","ts":"2026-10-03T02:34:33.29832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c43c9f84c6588d4 became leader at term 2"} {"level":"info","ts":"2026-10-03T02:34:33.298334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c43c9f84c6588d4 elected leader 4c43c9f84c6588d4 at term 2"} {"level":"info","ts":"2026-10-03T02:34:33.299308Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T02:34:33.300459Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T02:34:33.30054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-03T02:34:33.300447Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c43c9f84c6588d4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.73:2379]}","request-path":"/0/members/4c43c9f84c6588d4/attributes","cluster-id":"49c90045dbd991f3","publish-timeout":"7s"} {"level":"info","ts":"2026-10-03T02:34:33.300971Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-03T02:34:33.301036Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-03T02:34:33.301327Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49c90045dbd991f3","local-member-id":"4c43c9f84c6588d4","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T02:34:33.30148Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T02:34:33.301532Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-03T02:34:33.303964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.73:2379"} {"level":"info","ts":"2026-10-03T02:34:33.304294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-03T02:34:48.901454Z","caller":"traceutil/trace.go:171","msg":"trace[1624736489] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"135.742282ms","start":"2026-10-03T02:34:48.765689Z","end":"2026-10-03T02:34:48.901431Z","steps":["trace[1624736489] 'process raft request' (duration: 135.624258ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:34:48.953384Z","caller":"traceutil/trace.go:171","msg":"trace[639213207] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"177.086861ms","start":"2026-10-03T02:34:48.776281Z","end":"2026-10-03T02:34:48.953368Z","steps":["trace[639213207] 'process raft request' (duration: 176.897646ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:34:49.299781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.276809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T02:34:49.299897Z","caller":"traceutil/trace.go:171","msg":"trace[876495018] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"136.486002ms","start":"2026-10-03T02:34:49.163392Z","end":"2026-10-03T02:34:49.299878Z","steps":["trace[876495018] 'read index received' (duration: 52.712µs)","trace[876495018] 'applied index is now lower than readState.Index' (duration: 136.4319ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:34:49.299995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.603765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T02:34:49.30004Z","caller":"traceutil/trace.go:171","msg":"trace[1557388734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"136.687487ms","start":"2026-10-03T02:34:49.163339Z","end":"2026-10-03T02:34:49.300026Z","steps":["trace[1557388734] 'agreement among raft nodes before linearized reading' (duration: 136.595124ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:34:49.300067Z","caller":"traceutil/trace.go:171","msg":"trace[1400126858] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"343.082849ms","start":"2026-10-03T02:34:48.95693Z","end":"2026-10-03T02:34:49.300013Z","steps":["trace[1400126858] 'process raft request' (duration: 146.860175ms)","trace[1400126858] 'compare' (duration: 195.002491ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:34:49.300272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T02:34:48.956905Z","time spent":"343.270685ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T02:35:15.726158Z","caller":"traceutil/trace.go:171","msg":"trace[493657904] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"116.223628ms","start":"2026-10-03T02:35:15.609913Z","end":"2026-10-03T02:35:15.726136Z","steps":["trace[493657904] 'read index received' (duration: 115.411925ms)","trace[493657904] 'applied index is now lower than readState.Index' (duration: 810.743µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:35:15.726243Z","caller":"traceutil/trace.go:171","msg":"trace[1988023938] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"165.882016ms","start":"2026-10-03T02:35:15.560322Z","end":"2026-10-03T02:35:15.726204Z","steps":["trace[1988023938] 'process raft request' (duration: 165.31202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:35:15.726334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.410632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7wjkz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-10-03T02:35:15.726371Z","caller":"traceutil/trace.go:171","msg":"trace[394458817] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7wjkz; range_end:; response_count:1; response_revision:627; }","duration":"116.495335ms","start":"2026-10-03T02:35:15.609865Z","end":"2026-10-03T02:35:15.72636Z","steps":["trace[394458817] 'agreement among raft nodes before linearized reading' (duration: 116.360121ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:35:16.004258Z","caller":"traceutil/trace.go:171","msg":"trace[682706861] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"217.129357ms","start":"2026-10-03T02:35:15.787103Z","end":"2026-10-03T02:35:16.004232Z","steps":["trace[682706861] 'process raft request' (duration: 163.930762ms)","trace[682706861] 'compare' (duration: 52.939158ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:35:16.004356Z","caller":"traceutil/trace.go:171","msg":"trace[2034804445] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"206.139885ms","start":"2026-10-03T02:35:15.798192Z","end":"2026-10-03T02:35:16.004332Z","steps":["trace[2034804445] 'process raft request' (duration: 205.922709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:35:16.454058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.994971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T02:35:16.454175Z","caller":"traceutil/trace.go:171","msg":"trace[1762043165] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"363.821307ms","start":"2026-10-03T02:35:16.090335Z","end":"2026-10-03T02:35:16.454156Z","steps":["trace[1762043165] 'process raft request' (duration: 224.656243ms)","trace[1762043165] 'compare' (duration: 138.825955ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:35:16.454234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T02:35:16.090315Z","time spent":"363.888559ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T02:37:32.062486Z","caller":"traceutil/trace.go:171","msg":"trace[327552712] linearizableReadLoop","detail":"{readStateIndex:2242; appliedIndex:2241; }","duration":"120.395961ms","start":"2026-10-03T02:37:31.942045Z","end":"2026-10-03T02:37:32.062441Z","steps":["trace[327552712] 'read index received' (duration: 66.867916ms)","trace[327552712] 'applied index is now lower than readState.Index' (duration: 53.526675ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:37:32.062584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.511445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T02:37:32.062636Z","caller":"traceutil/trace.go:171","msg":"trace[672658326] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2162; }","duration":"120.642698ms","start":"2026-10-03T02:37:31.94198Z","end":"2026-10-03T02:37:32.062623Z","steps":["trace[672658326] 'agreement among raft nodes before linearized reading' (duration: 120.496494ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:37:32.361212Z","caller":"traceutil/trace.go:171","msg":"trace[219735819] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"125.021094ms","start":"2026-10-03T02:37:32.236159Z","end":"2026-10-03T02:37:32.36118Z","steps":["trace[219735819] 'process raft request' (duration: 124.642203ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:42:02.46664Z","caller":"traceutil/trace.go:171","msg":"trace[1478538955] transaction","detail":"{read_only:false; response_revision:3829; number_of_response:1; }","duration":"113.859352ms","start":"2026-10-03T02:42:02.35276Z","end":"2026-10-03T02:42:02.46662Z","steps":["trace[1478538955] 'process raft request' (duration: 113.679417ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:43:27.725902Z","caller":"traceutil/trace.go:171","msg":"trace[2039524878] linearizableReadLoop","detail":"{readStateIndex:5094; appliedIndex:5093; }","duration":"112.674062ms","start":"2026-10-03T02:43:27.613203Z","end":"2026-10-03T02:43:27.725877Z","steps":["trace[2039524878] 'read index received' (duration: 112.449706ms)","trace[2039524878] 'applied index is now lower than readState.Index' (duration: 223.116µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:43:27.726027Z","caller":"traceutil/trace.go:171","msg":"trace[1593909235] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"180.067485ms","start":"2026-10-03T02:43:27.545924Z","end":"2026-10-03T02:43:27.725992Z","steps":["trace[1593909235] 'process raft request' (duration: 179.769757ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:43:27.726167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.934809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T02:43:27.72627Z","caller":"traceutil/trace.go:171","msg":"trace[1352058094] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4908; }","duration":"113.097563ms","start":"2026-10-03T02:43:27.613151Z","end":"2026-10-03T02:43:27.726248Z","steps":["trace[1352058094] 'agreement among raft nodes before linearized reading' (duration: 112.860367ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:43:53.013997Z","caller":"traceutil/trace.go:171","msg":"trace[116490400] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"116.856339ms","start":"2026-10-03T02:43:52.897082Z","end":"2026-10-03T02:43:53.013938Z","steps":["trace[116490400] 'process raft request' (duration: 100.185886ms)","trace[116490400] 'compare' (duration: 16.275322ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:43:53.21154Z","caller":"traceutil/trace.go:171","msg":"trace[1938398256] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"155.686248ms","start":"2026-10-03T02:43:53.055803Z","end":"2026-10-03T02:43:53.211489Z","steps":["trace[1938398256] 'process raft request' (duration: 80.421475ms)","trace[1938398256] 'compare' (duration: 75.046577ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:43:53.211596Z","caller":"traceutil/trace.go:171","msg":"trace[572555454] linearizableReadLoop","detail":"{readStateIndex:5636; appliedIndex:5635; }","duration":"109.324069ms","start":"2026-10-03T02:43:53.102243Z","end":"2026-10-03T02:43:53.211567Z","steps":["trace[572555454] 'read index received' (duration: 33.990415ms)","trace[572555454] 'applied index is now lower than readState.Index' (duration: 75.331104ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:43:53.211596Z","caller":"traceutil/trace.go:171","msg":"trace[1772462813] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"103.066945ms","start":"2026-10-03T02:43:53.108504Z","end":"2026-10-03T02:43:53.211571Z","steps":["trace[1772462813] 'process raft request' (duration: 102.971802ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:43:53.211845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.289975ms","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-10-03T02:43:53.21189Z","caller":"traceutil/trace.go:171","msg":"trace[2062965643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5439; }","duration":"156.365326ms","start":"2026-10-03T02:43:53.055511Z","end":"2026-10-03T02:43:53.211876Z","steps":["trace[2062965643] 'agreement among raft nodes before linearized reading' (duration: 156.193102ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:43:53.520848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.059187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-03T02:43:53.521372Z","caller":"traceutil/trace.go:171","msg":"trace[1433862489] linearizableReadLoop","detail":"{readStateIndex:5639; appliedIndex:5637; }","duration":"222.000742ms","start":"2026-10-03T02:43:53.299325Z","end":"2026-10-03T02:43:53.521326Z","steps":["trace[1433862489] 'read index received' (duration: 19.40402ms)","trace[1433862489] 'applied index is now lower than readState.Index' (duration: 202.595662ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:43:53.521386Z","caller":"traceutil/trace.go:171","msg":"trace[64250973] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"304.832813ms","start":"2026-10-03T02:43:53.216521Z","end":"2026-10-03T02:43:53.521354Z","steps":["trace[64250973] 'process raft request' (duration: 304.421672ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:43:53.521375Z","caller":"traceutil/trace.go:171","msg":"trace[226718297] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"306.041197ms","start":"2026-10-03T02:43:53.215302Z","end":"2026-10-03T02:43:53.521343Z","steps":["trace[226718297] 'process raft request' (duration: 103.419175ms)","trace[226718297] 'compare' (duration: 201.887582ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:43:53.521931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T02:43:53.215281Z","time spent":"306.602793ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":896,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T02:43:53.521539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.202487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-51ad2b17-cbfc-45f4-bc55-0642659c8318\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-10-03T02:43:53.521745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.52311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kpf42\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-03T02:43:53.522051Z","caller":"traceutil/trace.go:171","msg":"trace[1091963461] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-51ad2b17-cbfc-45f4-bc55-0642659c8318; range_end:; response_count:1; response_revision:5441; }","duration":"222.720892ms","start":"2026-10-03T02:43:53.299289Z","end":"2026-10-03T02:43:53.52201Z","steps":["trace[1091963461] 'agreement among raft nodes before linearized reading' (duration: 222.144616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:43:53.522148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T02:43:53.216509Z","time spent":"305.292497ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T02:43:53.522138Z","caller":"traceutil/trace.go:171","msg":"trace[2060284150] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kpf42; range_end:; response_count:1; response_revision:5441; }","duration":"179.929062ms","start":"2026-10-03T02:43:53.342177Z","end":"2026-10-03T02:43:53.522106Z","steps":["trace[2060284150] 'agreement among raft nodes before linearized reading' (duration: 179.50344ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:44:04.601914Z","caller":"traceutil/trace.go:171","msg":"trace[1251071727] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"109.621778ms","start":"2026-10-03T02:44:04.492248Z","end":"2026-10-03T02:44:04.60187Z","steps":["trace[1251071727] 'process raft request' (duration: 82.438282ms)","trace[1251071727] 'compare' (duration: 26.96691ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:44:04.601957Z","caller":"traceutil/trace.go:171","msg":"trace[1723397417] transaction","detail":"{read_only:false; response_revision:5611; number_of_response:1; }","duration":"109.469023ms","start":"2026-10-03T02:44:04.492458Z","end":"2026-10-03T02:44:04.601927Z","steps":["trace[1723397417] 'process raft request' (duration: 109.322969ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:44:04.602095Z","caller":"traceutil/trace.go:171","msg":"trace[639054501] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"108.836146ms","start":"2026-10-03T02:44:04.49324Z","end":"2026-10-03T02:44:04.602076Z","steps":["trace[639054501] 'process raft request' (duration: 108.6263ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:44:33.360911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-10-03T02:44:33.431342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"68.821263ms","hash":2124849410,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":32808960,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T02:44:33.431407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2124849410,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-10-03T02:49:33.367682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5895} {"level":"info","ts":"2026-10-03T02:49:33.466743Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5895,"took":"97.306568ms","hash":3509184320,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":25513984,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-03T02:49:33.46682Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3509184320,"revision":5895,"compact-revision":2866} {"level":"warn","ts":"2026-10-03T02:49:52.948131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.654569ms","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-10-03T02:49:52.948257Z","caller":"traceutil/trace.go:171","msg":"trace[642368214] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:8258; }","duration":"123.850325ms","start":"2026-10-03T02:49:52.824381Z","end":"2026-10-03T02:49:52.948231Z","steps":["trace[642368214] 'count revisions from in-memory index tree' (duration: 123.582737ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:04.663438Z","caller":"traceutil/trace.go:171","msg":"trace[1959758571] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"111.155113ms","start":"2026-10-03T02:50:04.552256Z","end":"2026-10-03T02:50:04.663411Z","steps":["trace[1959758571] 'process raft request' (duration: 48.098494ms)","trace[1959758571] 'compare' (duration: 62.924845ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:50:04.663438Z","caller":"traceutil/trace.go:171","msg":"trace[272626587] linearizableReadLoop","detail":"{readStateIndex:8614; appliedIndex:8613; }","duration":"110.718161ms","start":"2026-10-03T02:50:04.552695Z","end":"2026-10-03T02:50:04.663413Z","steps":["trace[272626587] 'read index received' (duration: 47.669782ms)","trace[272626587] 'applied index is now lower than readState.Index' (duration: 63.047239ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:50:04.663805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.093111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-03T02:50:04.663947Z","caller":"traceutil/trace.go:171","msg":"trace[1943187035] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8321; }","duration":"111.284106ms","start":"2026-10-03T02:50:04.552644Z","end":"2026-10-03T02:50:04.663928Z","steps":["trace[1943187035] 'agreement among raft nodes before linearized reading' (duration: 110.841034ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:05.355285Z","caller":"traceutil/trace.go:171","msg":"trace[530790875] linearizableReadLoop","detail":"{readStateIndex:8617; appliedIndex:8616; }","duration":"192.278752ms","start":"2026-10-03T02:50:05.162984Z","end":"2026-10-03T02:50:05.355263Z","steps":["trace[530790875] 'read index received' (duration: 192.117688ms)","trace[530790875] 'applied index is now lower than readState.Index' (duration: 160.194µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:50:05.355339Z","caller":"traceutil/trace.go:171","msg":"trace[917691438] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"202.290141ms","start":"2026-10-03T02:50:05.153025Z","end":"2026-10-03T02:50:05.355315Z","steps":["trace[917691438] 'process raft request' (duration: 202.075675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:05.35544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.446838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T02:50:05.355476Z","caller":"traceutil/trace.go:171","msg":"trace[176204978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8324; }","duration":"192.52544ms","start":"2026-10-03T02:50:05.16294Z","end":"2026-10-03T02:50:05.355466Z","steps":["trace[176204978] 'agreement among raft nodes before linearized reading' (duration: 192.456748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:05.355489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.291869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T02:50:05.35557Z","caller":"traceutil/trace.go:171","msg":"trace[1239966620] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8324; }","duration":"141.417292ms","start":"2026-10-03T02:50:05.214131Z","end":"2026-10-03T02:50:05.355549Z","steps":["trace[1239966620] 'agreement among raft nodes before linearized reading' (duration: 141.291689ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:05.525757Z","caller":"traceutil/trace.go:171","msg":"trace[1295965306] linearizableReadLoop","detail":"{readStateIndex:8618; appliedIndex:8617; }","duration":"164.198434ms","start":"2026-10-03T02:50:05.361542Z","end":"2026-10-03T02:50:05.52574Z","steps":["trace[1295965306] 'read index received' (duration: 164.140603ms)","trace[1295965306] 'applied index is now lower than readState.Index' (duration: 56.891µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:50:05.525882Z","caller":"traceutil/trace.go:171","msg":"trace[1153801328] transaction","detail":"{read_only:false; response_revision:8325; number_of_response:1; }","duration":"164.855793ms","start":"2026-10-03T02:50:05.361002Z","end":"2026-10-03T02:50:05.525858Z","steps":["trace[1153801328] 'process raft request' (duration: 164.619806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:05.526005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.452741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-03T02:50:05.526046Z","caller":"traceutil/trace.go:171","msg":"trace[864740921] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8325; }","duration":"164.531044ms","start":"2026-10-03T02:50:05.361505Z","end":"2026-10-03T02:50:05.526036Z","steps":["trace[864740921] 'agreement among raft nodes before linearized reading' (duration: 164.342128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:05.536063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.230239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T02:50:05.536144Z","caller":"traceutil/trace.go:171","msg":"trace[1113626614] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8326; }","duration":"131.336042ms","start":"2026-10-03T02:50:05.404788Z","end":"2026-10-03T02:50:05.536124Z","steps":["trace[1113626614] 'agreement among raft nodes before linearized reading' (duration: 131.205949ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:33.33139Z","caller":"traceutil/trace.go:171","msg":"trace[128400879] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"132.860355ms","start":"2026-10-03T02:50:33.1985Z","end":"2026-10-03T02:50:33.33136Z","steps":["trace[128400879] 'process raft request' (duration: 132.639869ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:33.332565Z","caller":"traceutil/trace.go:171","msg":"trace[1622584325] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"131.190429ms","start":"2026-10-03T02:50:33.201308Z","end":"2026-10-03T02:50:33.332499Z","steps":["trace[1622584325] 'process raft request' (duration: 130.987213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:46.836165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.419654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-03T02:50:46.836552Z","caller":"traceutil/trace.go:171","msg":"trace[1163647223] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8642; }","duration":"177.102492ms","start":"2026-10-03T02:50:46.65943Z","end":"2026-10-03T02:50:46.836533Z","steps":["trace[1163647223] 'process raft request' (duration: 176.849215ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:46.836532Z","caller":"traceutil/trace.go:171","msg":"trace[1256436979] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"179.701954ms","start":"2026-10-03T02:50:46.656787Z","end":"2026-10-03T02:50:46.836489Z","steps":["trace[1256436979] 'process raft request' (duration: 73.872548ms)","trace[1256436979] 'compare' (duration: 105.134956ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:50:47.091653Z","caller":"traceutil/trace.go:171","msg":"trace[1072700143] linearizableReadLoop","detail":"{readStateIndex:8948; appliedIndex:8947; }","duration":"162.528198ms","start":"2026-10-03T02:50:46.929102Z","end":"2026-10-03T02:50:47.09163Z","steps":["trace[1072700143] 'read index received' (duration: 162.363293ms)","trace[1072700143] 'applied index is now lower than readState.Index' (duration: 163.835µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:50:47.09186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.749253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-10-03T02:50:47.091898Z","caller":"traceutil/trace.go:171","msg":"trace[14842196] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8643; }","duration":"162.838216ms","start":"2026-10-03T02:50:46.929048Z","end":"2026-10-03T02:50:47.091886Z","steps":["trace[14842196] 'agreement among raft nodes before linearized reading' (duration: 162.673781ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:47.092224Z","caller":"traceutil/trace.go:171","msg":"trace[1817180576] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"246.420635ms","start":"2026-10-03T02:50:46.845792Z","end":"2026-10-03T02:50:47.092212Z","steps":["trace[1817180576] 'process raft request' (duration: 245.688494ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:47.947617Z","caller":"traceutil/trace.go:171","msg":"trace[412720977] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"289.328595ms","start":"2026-10-03T02:50:47.658264Z","end":"2026-10-03T02:50:47.947593Z","steps":["trace[412720977] 'process raft request' (duration: 289.194451ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:47.958269Z","caller":"traceutil/trace.go:171","msg":"trace[3158257] transaction","detail":"{read_only:false; response_revision:8648; number_of_response:1; }","duration":"277.810535ms","start":"2026-10-03T02:50:47.680431Z","end":"2026-10-03T02:50:47.958241Z","steps":["trace[3158257] 'process raft request' (duration: 277.587648ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:47.95832Z","caller":"traceutil/trace.go:171","msg":"trace[1239940822] linearizableReadLoop","detail":"{readStateIndex:8953; appliedIndex:8951; }","duration":"233.445004ms","start":"2026-10-03T02:50:47.724849Z","end":"2026-10-03T02:50:47.958294Z","steps":["trace[1239940822] 'read index received' (duration: 222.610614ms)","trace[1239940822] 'applied index is now lower than readState.Index' (duration: 10.83298ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:50:47.95834Z","caller":"traceutil/trace.go:171","msg":"trace[681485734] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"172.990778ms","start":"2026-10-03T02:50:47.785332Z","end":"2026-10-03T02:50:47.958322Z","steps":["trace[681485734] 'process raft request' (duration: 172.792522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:47.958512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.579208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-03T02:50:47.958544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.618708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T02:50:47.958555Z","caller":"traceutil/trace.go:171","msg":"trace[1588524446] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8649; }","duration":"224.65525ms","start":"2026-10-03T02:50:47.733887Z","end":"2026-10-03T02:50:47.958542Z","steps":["trace[1588524446] 'agreement among raft nodes before linearized reading' (duration: 224.535167ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:47.958586Z","caller":"traceutil/trace.go:171","msg":"trace[1153004663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8649; }","duration":"233.771963ms","start":"2026-10-03T02:50:47.724801Z","end":"2026-10-03T02:50:47.958573Z","steps":["trace[1153004663] 'agreement among raft nodes before linearized reading' (duration: 233.601708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:47.958619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.236774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-zpcmq\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-10-03T02:50:47.958735Z","caller":"traceutil/trace.go:171","msg":"trace[1743674604] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-zpcmq; range_end:; response_count:1; response_revision:8649; }","duration":"173.43296ms","start":"2026-10-03T02:50:47.785281Z","end":"2026-10-03T02:50:47.958714Z","steps":["trace[1743674604] 'agreement among raft nodes before linearized reading' (duration: 173.160782ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T02:50:47.958646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.491117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-03T02:50:47.958854Z","caller":"traceutil/trace.go:171","msg":"trace[58256370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8649; }","duration":"210.741114ms","start":"2026-10-03T02:50:47.748104Z","end":"2026-10-03T02:50:47.958845Z","steps":["trace[58256370] 'agreement among raft nodes before linearized reading' (duration: 210.406605ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:50:48.550366Z","caller":"traceutil/trace.go:171","msg":"trace[1451281399] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"132.423653ms","start":"2026-10-03T02:50:48.417909Z","end":"2026-10-03T02:50:48.550333Z","steps":["trace[1451281399] 'process raft request' (duration: 132.137565ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:51:09.53192Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023fe.wal"} {"level":"info","ts":"2026-10-03T02:51:52.892765Z","caller":"traceutil/trace.go:171","msg":"trace[84049127] transaction","detail":"{read_only:false; response_revision:9263; number_of_response:1; }","duration":"107.989999ms","start":"2026-10-03T02:51:52.784753Z","end":"2026-10-03T02:51:52.892743Z","steps":["trace[84049127] 'process raft request' (duration: 107.854625ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:51:52.895056Z","caller":"traceutil/trace.go:171","msg":"trace[1455677636] transaction","detail":"{read_only:false; response_revision:9264; number_of_response:1; }","duration":"108.524114ms","start":"2026-10-03T02:51:52.786503Z","end":"2026-10-03T02:51:52.895027Z","steps":["trace[1455677636] 'process raft request' (duration: 108.39038ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:52:04.543276Z","caller":"traceutil/trace.go:171","msg":"trace[1263347704] transaction","detail":"{read_only:false; response_revision:9330; number_of_response:1; }","duration":"210.233708ms","start":"2026-10-03T02:52:04.333002Z","end":"2026-10-03T02:52:04.543235Z","steps":["trace[1263347704] 'process raft request' (duration: 209.93365ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:52:04.556087Z","caller":"traceutil/trace.go:171","msg":"trace[1227738105] transaction","detail":"{read_only:false; response_revision:9331; number_of_response:1; }","duration":"222.694523ms","start":"2026-10-03T02:52:04.333369Z","end":"2026-10-03T02:52:04.556064Z","steps":["trace[1227738105] 'process raft request' (duration: 222.478477ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:52:04.556015Z","caller":"traceutil/trace.go:171","msg":"trace[769795994] linearizableReadLoop","detail":"{readStateIndex:9657; appliedIndex:9655; }","duration":"114.116728ms","start":"2026-10-03T02:52:04.441871Z","end":"2026-10-03T02:52:04.555988Z","steps":["trace[769795994] 'read index received' (duration: 100.315346ms)","trace[769795994] 'applied index is now lower than readState.Index' (duration: 13.800632ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T02:52:04.55635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.501129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kpf42\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-03T02:52:04.556424Z","caller":"traceutil/trace.go:171","msg":"trace[654413224] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kpf42; range_end:; response_count:1; response_revision:9331; }","duration":"114.629473ms","start":"2026-10-03T02:52:04.441781Z","end":"2026-10-03T02:52:04.556411Z","steps":["trace[654413224] 'agreement among raft nodes before linearized reading' (duration: 114.384986ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:52:40.478373Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c43c9f84c6588d4","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T02:52:40.486907Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-03T02:52:40.487087Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-03T02:54:33.37494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7981} {"level":"info","ts":"2026-10-03T02:54:33.438244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7981,"took":"61.237955ms","hash":3290907365,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":28717056,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-03T02:54:33.438307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3290907365,"revision":7981,"compact-revision":5895} {"level":"info","ts":"2026-10-03T02:55:43.784055Z","caller":"traceutil/trace.go:171","msg":"trace[111385320] transaction","detail":"{read_only:false; response_revision:11254; number_of_response:1; }","duration":"108.112984ms","start":"2026-10-03T02:55:43.675902Z","end":"2026-10-03T02:55:43.784015Z","steps":["trace[111385320] 'process raft request' (duration: 107.897278ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T02:55:43.841556Z","caller":"traceutil/trace.go:171","msg":"trace[1204696066] transaction","detail":"{read_only:false; response_revision:11255; number_of_response:1; }","duration":"165.249737ms","start":"2026-10-03T02:55:43.676283Z","end":"2026-10-03T02:55:43.841532Z","steps":["trace[1204696066] 'process raft request' (duration: 138.221893ms)","trace[1204696066] 'compare' (duration: 26.87877ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T02:59:33.382953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10573} {"level":"info","ts":"2026-10-03T02:59:33.467426Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10573,"took":"82.080842ms","hash":1587979756,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":27222016,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-03T02:59:33.467527Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1587979756,"revision":10573,"compact-revision":7981} {"level":"info","ts":"2026-10-03T03:01:40.479933Z","caller":"traceutil/trace.go:171","msg":"trace[2083409216] transaction","detail":"{read_only:false; response_revision:13678; number_of_response:1; }","duration":"132.000688ms","start":"2026-10-03T03:01:40.347901Z","end":"2026-10-03T03:01:40.479902Z","steps":["trace[2083409216] 'process raft request' (duration: 131.905465ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:01:40.481759Z","caller":"traceutil/trace.go:171","msg":"trace[192614945] transaction","detail":"{read_only:false; response_revision:13679; number_of_response:1; }","duration":"133.062517ms","start":"2026-10-03T03:01:40.348681Z","end":"2026-10-03T03:01:40.481744Z","steps":["trace[192614945] 'process raft request' (duration: 133.006735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:02:01.840828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.24556ms","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-10-03T03:02:01.84099Z","caller":"traceutil/trace.go:171","msg":"trace[1047541480] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:13791; }","duration":"228.479067ms","start":"2026-10-03T03:02:01.612487Z","end":"2026-10-03T03:02:01.840967Z","steps":["trace[1047541480] 'count revisions from in-memory index tree' (duration: 228.144247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:02:01.841007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.839366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-03T03:02:01.84106Z","caller":"traceutil/trace.go:171","msg":"trace[150426078] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:13791; }","duration":"134.903848ms","start":"2026-10-03T03:02:01.706142Z","end":"2026-10-03T03:02:01.841046Z","steps":["trace[150426078] 'count revisions from in-memory index tree' (duration: 134.758614ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:02:01.843984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.808605ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-03T03:02:01.844066Z","caller":"traceutil/trace.go:171","msg":"trace[410354573] linearizableReadLoop","detail":"{readStateIndex:14264; appliedIndex:14263; }","duration":"123.38262ms","start":"2026-10-03T03:02:01.72067Z","end":"2026-10-03T03:02:01.844052Z","steps":["trace[410354573] 'read index received' (duration: 8.448012ms)","trace[410354573] 'applied index is now lower than readState.Index' (duration: 114.933258ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:02:01.84425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.577606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kpf42\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-10-03T03:02:01.844285Z","caller":"traceutil/trace.go:171","msg":"trace[124460060] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kpf42; range_end:; response_count:1; response_revision:13791; }","duration":"123.652588ms","start":"2026-10-03T03:02:01.720622Z","end":"2026-10-03T03:02:01.844274Z","steps":["trace[124460060] 'agreement among raft nodes before linearized reading' (duration: 123.515844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:02:18.856279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.361509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-03T03:02:18.856393Z","caller":"traceutil/trace.go:171","msg":"trace[1502195049] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13891; }","duration":"152.534674ms","start":"2026-10-03T03:02:18.703831Z","end":"2026-10-03T03:02:18.856366Z","steps":["trace[1502195049] 'range keys from in-memory index tree' (duration: 152.150373ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:02:18.856438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.217938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-03T03:02:18.856569Z","caller":"traceutil/trace.go:171","msg":"trace[1544276301] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:13891; }","duration":"323.415573ms","start":"2026-10-03T03:02:18.533127Z","end":"2026-10-03T03:02:18.856543Z","steps":["trace[1544276301] 'count revisions from in-memory index tree' (duration: 323.025323ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:02:18.856565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.717029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-03T03:02:18.856627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:02:18.533104Z","time spent":"323.509266ms","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-10-03T03:02:18.856667Z","caller":"traceutil/trace.go:171","msg":"trace[666120416] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:13891; }","duration":"279.930365ms","start":"2026-10-03T03:02:18.576712Z","end":"2026-10-03T03:02:18.856642Z","steps":["trace[666120416] 'count revisions from in-memory index tree' (duration: 279.584785ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:02:18.856672Z","caller":"traceutil/trace.go:171","msg":"trace[55642120] transaction","detail":"{read_only:false; response_revision:13892; number_of_response:1; }","duration":"131.239256ms","start":"2026-10-03T03:02:18.725396Z","end":"2026-10-03T03:02:18.856635Z","steps":["trace[55642120] 'process raft request' (duration: 100.89407ms)","trace[55642120] 'compare' (duration: 29.816152ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:02:18.856692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.316754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-10-03T03:02:18.856815Z","caller":"traceutil/trace.go:171","msg":"trace[136197978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13891; }","duration":"141.51285ms","start":"2026-10-03T03:02:18.715266Z","end":"2026-10-03T03:02:18.856779Z","steps":["trace[136197978] 'range keys from in-memory index tree' (duration: 140.871623ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:02:42.989497Z","caller":"traceutil/trace.go:171","msg":"trace[2139135873] transaction","detail":"{read_only:false; response_revision:14217; number_of_response:1; }","duration":"195.521311ms","start":"2026-10-03T03:02:42.793946Z","end":"2026-10-03T03:02:42.989468Z","steps":["trace[2139135873] 'process raft request' (duration: 195.355247ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:02:43.107114Z","caller":"traceutil/trace.go:171","msg":"trace[989662091] transaction","detail":"{read_only:false; response_revision:14220; number_of_response:1; }","duration":"100.73807ms","start":"2026-10-03T03:02:43.006332Z","end":"2026-10-03T03:02:43.10707Z","steps":["trace[989662091] 'process raft request' (duration: 59.932273ms)","trace[989662091] 'compare' (duration: 40.356975ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:03:41.27716Z","caller":"traceutil/trace.go:171","msg":"trace[1826148573] transaction","detail":"{read_only:false; response_revision:14878; number_of_response:1; }","duration":"106.618699ms","start":"2026-10-03T03:03:41.170518Z","end":"2026-10-03T03:03:41.277136Z","steps":["trace[1826148573] 'process raft request' (duration: 106.465475ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:04:12.337719Z","caller":"traceutil/trace.go:171","msg":"trace[662861874] linearizableReadLoop","detail":"{readStateIndex:15556; appliedIndex:15555; }","duration":"133.709528ms","start":"2026-10-03T03:04:12.20397Z","end":"2026-10-03T03:04:12.337679Z","steps":["trace[662861874] 'read index received' (duration: 133.462372ms)","trace[662861874] 'applied index is now lower than readState.Index' (duration: 245.126µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:04:12.337929Z","caller":"traceutil/trace.go:171","msg":"trace[426923844] transaction","detail":"{read_only:false; response_revision:15042; number_of_response:1; }","duration":"142.358771ms","start":"2026-10-03T03:04:12.195517Z","end":"2026-10-03T03:04:12.337875Z","steps":["trace[426923844] 'process raft request' (duration: 141.922799ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:04:12.338056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.067658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jzmj8\" ","response":"range_response_count:1 size:22128"} {"level":"info","ts":"2026-10-03T03:04:12.338169Z","caller":"traceutil/trace.go:171","msg":"trace[1103196402] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jzmj8; range_end:; response_count:1; response_revision:15042; }","duration":"134.237573ms","start":"2026-10-03T03:04:12.203906Z","end":"2026-10-03T03:04:12.338144Z","steps":["trace[1103196402] 'agreement among raft nodes before linearized reading' (duration: 133.963355ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:04:33.390036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12737} {"level":"info","ts":"2026-10-03T03:04:33.476912Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12737,"took":"83.181518ms","hash":3372658133,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":29331456,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-03T03:04:33.476992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3372658133,"revision":12737,"compact-revision":10573} {"level":"info","ts":"2026-10-03T03:09:33.398203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15149} {"level":"info","ts":"2026-10-03T03:09:33.507249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15149,"took":"104.07635ms","hash":4208185496,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":31596544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T03:09:33.507349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4208185496,"revision":15149,"compact-revision":12737} {"level":"info","ts":"2026-10-03T03:09:49.667145Z","caller":"traceutil/trace.go:171","msg":"trace[1562050863] transaction","detail":"{read_only:false; response_revision:17604; number_of_response:1; }","duration":"111.435501ms","start":"2026-10-03T03:09:49.555676Z","end":"2026-10-03T03:09:49.667112Z","steps":["trace[1562050863] 'process raft request' (duration: 110.764143ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:09:55.465648Z","caller":"traceutil/trace.go:171","msg":"trace[200274753] transaction","detail":"{read_only:false; response_revision:17639; number_of_response:1; }","duration":"212.410017ms","start":"2026-10-03T03:09:55.253194Z","end":"2026-10-03T03:09:55.465604Z","steps":["trace[200274753] 'process raft request' (duration: 212.1773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:09:55.466059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.654217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-p76vf\" ","response":"range_response_count:1 size:20758"} {"level":"info","ts":"2026-10-03T03:09:55.466159Z","caller":"traceutil/trace.go:171","msg":"trace[109739892] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-p76vf; range_end:; response_count:1; response_revision:17639; }","duration":"148.821801ms","start":"2026-10-03T03:09:55.317312Z","end":"2026-10-03T03:09:55.466134Z","steps":["trace[109739892] 'agreement among raft nodes before linearized reading' (duration: 148.506873ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:09:55.466168Z","caller":"traceutil/trace.go:171","msg":"trace[505142562] linearizableReadLoop","detail":"{readStateIndex:18237; appliedIndex:18236; }","duration":"148.76961ms","start":"2026-10-03T03:09:55.317383Z","end":"2026-10-03T03:09:55.466152Z","steps":["trace[505142562] 'read index received' (duration: 147.961668ms)","trace[505142562] 'applied index is now lower than readState.Index' (duration: 807.052µs)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:09:55.490355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.141106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-jzmj8\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-10-03T03:09:55.490402Z","caller":"traceutil/trace.go:171","msg":"trace[1368543826] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-jzmj8; range_end:; response_count:1; response_revision:17640; }","duration":"169.269739ms","start":"2026-10-03T03:09:55.32112Z","end":"2026-10-03T03:09:55.49039Z","steps":["trace[1368543826] 'agreement among raft nodes before linearized reading' (duration: 169.067464ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:09:55.490717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.323324ms","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:8"} {"level":"info","ts":"2026-10-03T03:09:55.490754Z","caller":"traceutil/trace.go:171","msg":"trace[1753978705] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:17640; }","duration":"142.420587ms","start":"2026-10-03T03:09:55.348321Z","end":"2026-10-03T03:09:55.490741Z","steps":["trace[1753978705] 'agreement among raft nodes before linearized reading' (duration: 142.353105ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:09:55.734877Z","caller":"traceutil/trace.go:171","msg":"trace[648320691] transaction","detail":"{read_only:false; response_revision:17645; number_of_response:1; }","duration":"228.575139ms","start":"2026-10-03T03:09:55.506268Z","end":"2026-10-03T03:09:55.734843Z","steps":["trace[648320691] 'process raft request' (duration: 228.26076ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:09:56.13766Z","caller":"traceutil/trace.go:171","msg":"trace[903201955] linearizableReadLoop","detail":"{readStateIndex:18250; appliedIndex:18246; }","duration":"320.667621ms","start":"2026-10-03T03:09:55.816968Z","end":"2026-10-03T03:09:56.137636Z","steps":["trace[903201955] 'read index received' (duration: 245.187651ms)","trace[903201955] 'applied index is now lower than readState.Index' (duration: 75.47885ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:09:56.137803Z","caller":"traceutil/trace.go:171","msg":"trace[1700918536] transaction","detail":"{read_only:false; response_revision:17649; number_of_response:1; }","duration":"329.672247ms","start":"2026-10-03T03:09:55.808116Z","end":"2026-10-03T03:09:56.137789Z","steps":["trace[1700918536] 'process raft request' (duration: 254.121675ms)","trace[1700918536] 'compare' (duration: 74.844533ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:09:56.137935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:09:55.808071Z","time spent":"329.7835ms","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":658,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T03:09:56.138214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.58974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-03T03:09:56.13826Z","caller":"traceutil/trace.go:171","msg":"trace[1070412855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17652; }","duration":"320.680102ms","start":"2026-10-03T03:09:55.817565Z","end":"2026-10-03T03:09:56.138246Z","steps":["trace[1070412855] 'agreement among raft nodes before linearized reading' (duration: 320.489567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:09:56.138286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:09:55.817546Z","time spent":"320.733453ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-03T03:09:56.138192Z","caller":"traceutil/trace.go:171","msg":"trace[638227471] transaction","detail":"{read_only:false; response_revision:17650; number_of_response:1; }","duration":"329.476692ms","start":"2026-10-03T03:09:55.808685Z","end":"2026-10-03T03:09:56.138161Z","steps":["trace[638227471] 'process raft request' (duration: 328.752992ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:09:56.138437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:09:55.808673Z","time spent":"329.673517ms","remote":"127.0.0.1:56346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-03T03:09:56.138592Z","caller":"traceutil/trace.go:171","msg":"trace[1404877234] transaction","detail":"{read_only:false; response_revision:17652; number_of_response:1; }","duration":"329.588705ms","start":"2026-10-03T03:09:55.808992Z","end":"2026-10-03T03:09:56.138581Z","steps":["trace[1404877234] 'process raft request' (duration: 328.584748ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:09:56.138643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:09:55.808981Z","time spent":"329.636776ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T03:09:56.138643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.298135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7sbjc\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-10-03T03:09:56.138703Z","caller":"traceutil/trace.go:171","msg":"trace[1480065894] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7sbjc; range_end:; response_count:1; response_revision:17652; }","duration":"278.403128ms","start":"2026-10-03T03:09:55.860283Z","end":"2026-10-03T03:09:56.138686Z","steps":["trace[1480065894] 'agreement among raft nodes before linearized reading' (duration: 278.183402ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:09:56.138735Z","caller":"traceutil/trace.go:171","msg":"trace[1520419071] transaction","detail":"{read_only:false; response_revision:17651; number_of_response:1; }","duration":"329.906504ms","start":"2026-10-03T03:09:55.808816Z","end":"2026-10-03T03:09:56.138723Z","steps":["trace[1520419071] 'process raft request' (duration: 328.711211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:09:56.138789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:09:55.808793Z","time spent":"329.969785ms","remote":"127.0.0.1:56504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1133,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-03T03:09:56.141154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.133936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-10-03T03:09:56.141273Z","caller":"traceutil/trace.go:171","msg":"trace[90396822] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:17652; }","duration":"324.301891ms","start":"2026-10-03T03:09:55.816937Z","end":"2026-10-03T03:09:56.141238Z","steps":["trace[90396822] 'agreement among raft nodes before linearized reading' (duration: 321.030871ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:09:56.14132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:09:55.816927Z","time spent":"324.383743ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-10-03T03:09:56.274554Z","caller":"traceutil/trace.go:171","msg":"trace[1254912949] transaction","detail":"{read_only:false; response_revision:17654; number_of_response:1; }","duration":"127.499839ms","start":"2026-10-03T03:09:56.147015Z","end":"2026-10-03T03:09:56.274515Z","steps":["trace[1254912949] 'process raft request' (duration: 94.034405ms)","trace[1254912949] 'compare' (duration: 33.151815ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:09:56.274629Z","caller":"traceutil/trace.go:171","msg":"trace[659711729] linearizableReadLoop","detail":"{readStateIndex:18254; appliedIndex:18251; }","duration":"124.573849ms","start":"2026-10-03T03:09:56.150025Z","end":"2026-10-03T03:09:56.274599Z","steps":["trace[659711729] 'read index received' (duration: 69.095185ms)","trace[659711729] 'applied index is now lower than readState.Index' (duration: 55.477234ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:09:56.274932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.867987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-10-03T03:09:56.274976Z","caller":"traceutil/trace.go:171","msg":"trace[1495838863] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:17656; }","duration":"124.941569ms","start":"2026-10-03T03:09:56.150019Z","end":"2026-10-03T03:09:56.27496Z","steps":["trace[1495838863] 'agreement among raft nodes before linearized reading' (duration: 124.689372ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:09:56.274984Z","caller":"traceutil/trace.go:171","msg":"trace[204724407] transaction","detail":"{read_only:false; response_revision:17655; number_of_response:1; }","duration":"127.480398ms","start":"2026-10-03T03:09:56.147437Z","end":"2026-10-03T03:09:56.274917Z","steps":["trace[204724407] 'process raft request' (duration: 126.998285ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:09:56.27538Z","caller":"traceutil/trace.go:171","msg":"trace[1832829822] transaction","detail":"{read_only:false; response_revision:17656; number_of_response:1; }","duration":"127.006625ms","start":"2026-10-03T03:09:56.148361Z","end":"2026-10-03T03:09:56.275367Z","steps":["trace[1832829822] 'process raft request' (duration: 126.142872ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:10:03.03484Z","caller":"traceutil/trace.go:171","msg":"trace[1303999479] transaction","detail":"{read_only:false; response_revision:17763; number_of_response:1; }","duration":"115.841431ms","start":"2026-10-03T03:10:02.918969Z","end":"2026-10-03T03:10:03.03481Z","steps":["trace[1303999479] 'process raft request' (duration: 115.020589ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:12:46.709476Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c43c9f84c6588d4","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T03:12:46.716265Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-03T03:12:46.717359Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-03T03:14:33.408214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17484} {"level":"info","ts":"2026-10-03T03:14:33.515715Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17484,"took":"102.56871ms","hash":289440177,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":35078144,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-10-03T03:14:33.515804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":289440177,"revision":17484,"compact-revision":15149} {"level":"warn","ts":"2026-10-03T03:17:25.211198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.639585ms","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-10-03T03:17:25.211303Z","caller":"traceutil/trace.go:171","msg":"trace[1533922317] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21357; }","duration":"174.795029ms","start":"2026-10-03T03:17:25.03648Z","end":"2026-10-03T03:17:25.211275Z","steps":["trace[1533922317] 'range keys from in-memory index tree' (duration: 174.367538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:25.211276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.506812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-03T03:17:25.21137Z","caller":"traceutil/trace.go:171","msg":"trace[941507917] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:21357; }","duration":"178.678637ms","start":"2026-10-03T03:17:25.032669Z","end":"2026-10-03T03:17:25.211347Z","steps":["trace[941507917] 'range keys from in-memory index tree' (duration: 178.300876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:35.652974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.863481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-10-03T03:17:35.653076Z","caller":"traceutil/trace.go:171","msg":"trace[1773212616] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:21412; }","duration":"133.029706ms","start":"2026-10-03T03:17:35.52002Z","end":"2026-10-03T03:17:35.653049Z","steps":["trace[1773212616] 'range keys from in-memory index tree' (duration: 132.652095ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:17:35.777606Z","caller":"traceutil/trace.go:171","msg":"trace[874502241] transaction","detail":"{read_only:false; response_revision:21413; number_of_response:1; }","duration":"117.172524ms","start":"2026-10-03T03:17:35.660407Z","end":"2026-10-03T03:17:35.77758Z","steps":["trace[874502241] 'process raft request' (duration: 116.988849ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:17:36.072308Z","caller":"traceutil/trace.go:171","msg":"trace[509252822] transaction","detail":"{read_only:false; response_revision:21414; number_of_response:1; }","duration":"117.270877ms","start":"2026-10-03T03:17:35.955002Z","end":"2026-10-03T03:17:36.072272Z","steps":["trace[509252822] 'process raft request' (duration: 117.092232ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:17:36.076363Z","caller":"traceutil/trace.go:171","msg":"trace[1101966527] transaction","detail":"{read_only:false; response_revision:21415; number_of_response:1; }","duration":"120.752223ms","start":"2026-10-03T03:17:35.955578Z","end":"2026-10-03T03:17:36.07633Z","steps":["trace[1101966527] 'process raft request' (duration: 120.585529ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:36.318879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.10769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:17:36.318977Z","caller":"traceutil/trace.go:171","msg":"trace[1136180680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21415; }","duration":"155.231694ms","start":"2026-10-03T03:17:36.163723Z","end":"2026-10-03T03:17:36.318955Z","steps":["trace[1136180680] 'range keys from in-memory index tree' (duration: 155.031459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:36.319098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.510053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:17:36.319169Z","caller":"traceutil/trace.go:171","msg":"trace[354288839] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:21415; }","duration":"131.626686ms","start":"2026-10-03T03:17:36.187521Z","end":"2026-10-03T03:17:36.319148Z","steps":["trace[354288839] 'count revisions from in-memory index tree' (duration: 131.425661ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:17:36.718159Z","caller":"traceutil/trace.go:171","msg":"trace[31789489] linearizableReadLoop","detail":"{readStateIndex:22132; appliedIndex:22131; }","duration":"139.420704ms","start":"2026-10-03T03:17:36.578709Z","end":"2026-10-03T03:17:36.71813Z","steps":["trace[31789489] 'read index received' (duration: 139.172767ms)","trace[31789489] 'applied index is now lower than readState.Index' (duration: 246.067µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:17:36.718304Z","caller":"traceutil/trace.go:171","msg":"trace[1447200564] transaction","detail":"{read_only:false; response_revision:21417; number_of_response:1; }","duration":"155.582744ms","start":"2026-10-03T03:17:36.562691Z","end":"2026-10-03T03:17:36.718273Z","steps":["trace[1447200564] 'process raft request' (duration: 155.232964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:36.718496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.753623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-03T03:17:36.718544Z","caller":"traceutil/trace.go:171","msg":"trace[1301892538] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21417; }","duration":"139.865696ms","start":"2026-10-03T03:17:36.578664Z","end":"2026-10-03T03:17:36.71853Z","steps":["trace[1301892538] 'agreement among raft nodes before linearized reading' (duration: 139.614709ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:17:36.94528Z","caller":"traceutil/trace.go:171","msg":"trace[1077705356] linearizableReadLoop","detail":"{readStateIndex:22133; appliedIndex:22132; }","duration":"226.985873ms","start":"2026-10-03T03:17:36.718235Z","end":"2026-10-03T03:17:36.94522Z","steps":["trace[1077705356] 'read index received' (duration: 130.738582ms)","trace[1077705356] 'applied index is now lower than readState.Index' (duration: 96.245651ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:17:36.945316Z","caller":"traceutil/trace.go:171","msg":"trace[660427636] transaction","detail":"{read_only:false; response_revision:21418; number_of_response:1; }","duration":"253.367598ms","start":"2026-10-03T03:17:36.691896Z","end":"2026-10-03T03:17:36.945264Z","steps":["trace[660427636] 'process raft request' (duration: 157.062625ms)","trace[660427636] 'compare' (duration: 96.124358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:17:36.945499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.825555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T03:17:36.945606Z","caller":"traceutil/trace.go:171","msg":"trace[192027733] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:21418; }","duration":"336.030991ms","start":"2026-10-03T03:17:36.609551Z","end":"2026-10-03T03:17:36.945582Z","steps":["trace[192027733] 'agreement among raft nodes before linearized reading' (duration: 335.777204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:36.945657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:17:36.609519Z","time spent":"336.128234ms","remote":"127.0.0.1:56490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":8,"response size":30,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-10-03T03:17:36.945539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.073597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-10-03T03:17:36.945796Z","caller":"traceutil/trace.go:171","msg":"trace[1098286208] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:21418; }","duration":"357.336024ms","start":"2026-10-03T03:17:36.588394Z","end":"2026-10-03T03:17:36.94573Z","steps":["trace[1098286208] 'agreement among raft nodes before linearized reading' (duration: 357.017055ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:36.945908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.985013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-03T03:17:36.945962Z","caller":"traceutil/trace.go:171","msg":"trace[258501650] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21418; }","duration":"222.088377ms","start":"2026-10-03T03:17:36.723858Z","end":"2026-10-03T03:17:36.945946Z","steps":["trace[258501650] 'agreement among raft nodes before linearized reading' (duration: 221.895391ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:17:36.945977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:17:36.588389Z","time spent":"357.53758ms","remote":"127.0.0.1:56422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-10-03T03:17:36.945971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.043016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.73\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-03T03:17:36.946043Z","caller":"traceutil/trace.go:171","msg":"trace[582917496] range","detail":"{range_begin:/registry/masterleases/162.253.55.73; range_end:; response_count:1; response_revision:21418; }","duration":"222.154738ms","start":"2026-10-03T03:17:36.723872Z","end":"2026-10-03T03:17:36.946027Z","steps":["trace[582917496] 'agreement among raft nodes before linearized reading' (duration: 221.86584ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:19:33.416707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20111} {"level":"info","ts":"2026-10-03T03:19:33.536466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20111,"took":"115.118247ms","hash":3903508112,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T03:19:33.536556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3903508112,"revision":20111,"compact-revision":17484} {"level":"info","ts":"2026-10-03T03:21:24.873742Z","caller":"traceutil/trace.go:171","msg":"trace[923836193] transaction","detail":"{read_only:false; response_revision:23869; number_of_response:1; }","duration":"110.899306ms","start":"2026-10-03T03:21:24.762794Z","end":"2026-10-03T03:21:24.873694Z","steps":["trace[923836193] 'process raft request' (duration: 110.501985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:21:25.207039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.563276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:21:25.207449Z","caller":"traceutil/trace.go:171","msg":"trace[1078657852] linearizableReadLoop","detail":"{readStateIndex:24660; appliedIndex:24657; }","duration":"152.710403ms","start":"2026-10-03T03:21:25.05471Z","end":"2026-10-03T03:21:25.20742Z","steps":["trace[1078657852] 'read index received' (duration: 150.414459ms)","trace[1078657852] 'applied index is now lower than readState.Index' (duration: 2.290384ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:21:25.20755Z","caller":"traceutil/trace.go:171","msg":"trace[1716044616] transaction","detail":"{read_only:false; response_revision:23871; number_of_response:1; }","duration":"217.940694ms","start":"2026-10-03T03:21:24.989563Z","end":"2026-10-03T03:21:25.207504Z","steps":["trace[1716044616] 'process raft request' (duration: 217.621385ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:21:25.207611Z","caller":"traceutil/trace.go:171","msg":"trace[635915468] transaction","detail":"{read_only:false; response_revision:23872; number_of_response:1; }","duration":"179.768358ms","start":"2026-10-03T03:21:25.027824Z","end":"2026-10-03T03:21:25.207593Z","steps":["trace[635915468] 'process raft request' (duration: 179.45801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:21:25.207832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.088374ms","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-10-03T03:21:25.207951Z","caller":"traceutil/trace.go:171","msg":"trace[1540913432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23873; }","duration":"153.244488ms","start":"2026-10-03T03:21:25.054672Z","end":"2026-10-03T03:21:25.207916Z","steps":["trace[1540913432] 'agreement among raft nodes before linearized reading' (duration: 152.907968ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:21:25.207738Z","caller":"traceutil/trace.go:171","msg":"trace[1318063219] transaction","detail":"{read_only:false; response_revision:23870; number_of_response:1; }","duration":"321.222208ms","start":"2026-10-03T03:21:24.88648Z","end":"2026-10-03T03:21:25.207702Z","steps":["trace[1318063219] 'process raft request' (duration: 103.846759ms)","trace[1318063219] 'compare' (duration: 216.34587ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:21:25.208646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:21:24.886438Z","time spent":"321.927297ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:23:32.768643Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006583.wal"} {"level":"info","ts":"2026-10-03T03:24:33.427841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22370} {"level":"info","ts":"2026-10-03T03:24:33.538839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22370,"took":"105.757177ms","hash":3368696826,"current-db-size-bytes":56426496,"current-db-size":"56 MB","current-db-size-in-use-bytes":45953024,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-03T03:24:33.538916Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3368696826,"revision":22370,"compact-revision":20111} {"level":"info","ts":"2026-10-03T03:24:46.060307Z","caller":"traceutil/trace.go:171","msg":"trace[1662267940] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"244.324944ms","start":"2026-10-03T03:24:45.815944Z","end":"2026-10-03T03:24:46.060268Z","steps":["trace[1662267940] 'process raft request' (duration: 244.106338ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:46.395486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.816456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:24:46.395772Z","caller":"traceutil/trace.go:171","msg":"trace[1501994948] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26723; }","duration":"230.339816ms","start":"2026-10-03T03:24:46.165406Z","end":"2026-10-03T03:24:46.395745Z","steps":["trace[1501994948] 'read index received' (duration: 65.042356ms)","trace[1501994948] 'applied index is now lower than readState.Index' (duration: 165.2956ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:46.395858Z","caller":"traceutil/trace.go:171","msg":"trace[1183576827] transaction","detail":"{read_only:false; response_revision:25885; number_of_response:1; }","duration":"306.740628ms","start":"2026-10-03T03:24:46.08906Z","end":"2026-10-03T03:24:46.395801Z","steps":["trace[1183576827] 'process raft request' (duration: 141.485349ms)","trace[1183576827] 'compare' (duration: 164.030785ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:46.396073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:46.089042Z","time spent":"306.915082ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:24:46.396047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.514071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:24:46.396162Z","caller":"traceutil/trace.go:171","msg":"trace[269689011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25885; }","duration":"230.774028ms","start":"2026-10-03T03:24:46.165363Z","end":"2026-10-03T03:24:46.396137Z","steps":["trace[269689011] 'agreement among raft nodes before linearized reading' (duration: 230.47188ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:50.850066Z","caller":"traceutil/trace.go:171","msg":"trace[251799702] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"120.047273ms","start":"2026-10-03T03:24:50.729978Z","end":"2026-10-03T03:24:50.850025Z","steps":["trace[251799702] 'process raft request' (duration: 119.734745ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:51.051507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.404285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:24:51.051754Z","caller":"traceutil/trace.go:171","msg":"trace[626464071] linearizableReadLoop","detail":"{readStateIndex:26765; appliedIndex:26763; }","duration":"256.686406ms","start":"2026-10-03T03:24:50.795044Z","end":"2026-10-03T03:24:51.051731Z","steps":["trace[626464071] 'read index received' (duration: 54.636467ms)","trace[626464071] 'applied index is now lower than readState.Index' (duration: 202.048589ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:51.052095Z","caller":"traceutil/trace.go:171","msg":"trace[26214350] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"320.458798ms","start":"2026-10-03T03:24:50.731614Z","end":"2026-10-03T03:24:51.052073Z","steps":["trace[26214350] 'process raft request' (duration: 191.386455ms)","trace[26214350] 'compare' (duration: 128.292121ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:51.052151Z","caller":"traceutil/trace.go:171","msg":"trace[771896468] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"229.646826ms","start":"2026-10-03T03:24:50.822469Z","end":"2026-10-03T03:24:51.052116Z","steps":["trace[771896468] 'process raft request' (duration: 229.183573ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:51.052197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:50.731593Z","time spent":"320.55165ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:24:51.052215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.132779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T03:24:51.052271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.222249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T03:24:51.052307Z","caller":"traceutil/trace.go:171","msg":"trace[864577466] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25926; }","duration":"257.296014ms","start":"2026-10-03T03:24:50.794989Z","end":"2026-10-03T03:24:51.052285Z","steps":["trace[864577466] 'agreement among raft nodes before linearized reading' (duration: 257.109108ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:51.052346Z","caller":"traceutil/trace.go:171","msg":"trace[1870295511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25926; }","duration":"151.334112ms","start":"2026-10-03T03:24:50.900986Z","end":"2026-10-03T03:24:51.05232Z","steps":["trace[1870295511] 'agreement among raft nodes before linearized reading' (duration: 151.117066ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:51.194859Z","caller":"traceutil/trace.go:171","msg":"trace[1548843077] linearizableReadLoop","detail":"{readStateIndex:26767; appliedIndex:26766; }","duration":"117.085081ms","start":"2026-10-03T03:24:51.07775Z","end":"2026-10-03T03:24:51.194835Z","steps":["trace[1548843077] 'read index received' (duration: 116.892866ms)","trace[1548843077] 'applied index is now lower than readState.Index' (duration: 190.795µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:51.194922Z","caller":"traceutil/trace.go:171","msg":"trace[1805667924] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"137.419905ms","start":"2026-10-03T03:24:51.057469Z","end":"2026-10-03T03:24:51.194889Z","steps":["trace[1805667924] 'process raft request' (duration: 137.192589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:51.195019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.217464ms","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-10-03T03:24:51.195063Z","caller":"traceutil/trace.go:171","msg":"trace[1523662908] 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:25927; }","duration":"117.307277ms","start":"2026-10-03T03:24:51.077742Z","end":"2026-10-03T03:24:51.195049Z","steps":["trace[1523662908] 'agreement among raft nodes before linearized reading' (duration: 117.190104ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:51.648551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.251905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:24:51.648853Z","caller":"traceutil/trace.go:171","msg":"trace[1289663781] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"419.972292ms","start":"2026-10-03T03:24:51.228858Z","end":"2026-10-03T03:24:51.64883Z","steps":["trace[1289663781] 'process raft request' (duration: 145.342706ms)","trace[1289663781] 'compare' (duration: 274.113632ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:51.648966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:51.228828Z","time spent":"420.069695ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:24:51.649191Z","caller":"traceutil/trace.go:171","msg":"trace[1958925887] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"358.934007ms","start":"2026-10-03T03:24:51.290221Z","end":"2026-10-03T03:24:51.649155Z","steps":["trace[1958925887] 'process raft request' (duration: 358.459654ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:51.649355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:51.290189Z","time spent":"359.076431ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:24:51.851983Z","caller":"traceutil/trace.go:171","msg":"trace[1716512148] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"139.865284ms","start":"2026-10-03T03:24:51.712082Z","end":"2026-10-03T03:24:51.851948Z","steps":["trace[1716512148] 'process raft request' (duration: 138.663441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:52.122899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.600592ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-03T03:24:52.226717Z","caller":"traceutil/trace.go:171","msg":"trace[862661127] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"145.691385ms","start":"2026-10-03T03:24:52.080988Z","end":"2026-10-03T03:24:52.22668Z","steps":["trace[862661127] 'process raft request' (duration: 145.392127ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:52.527176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.330304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:24:52.527417Z","caller":"traceutil/trace.go:171","msg":"trace[1446244644] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"210.611389ms","start":"2026-10-03T03:24:52.316779Z","end":"2026-10-03T03:24:52.527391Z","steps":["trace[1446244644] 'process raft request' (duration: 210.519296ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:52.527466Z","caller":"traceutil/trace.go:171","msg":"trace[1822953922] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"423.292554ms","start":"2026-10-03T03:24:52.104123Z","end":"2026-10-03T03:24:52.527415Z","steps":["trace[1822953922] 'process raft request' (duration: 294.639321ms)","trace[1822953922] 'compare' (duration: 127.995095ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:52.52766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:52.104099Z","time spent":"423.451439ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:24:52.527709Z","caller":"traceutil/trace.go:171","msg":"trace[1101784545] linearizableReadLoop","detail":"{readStateIndex:26774; appliedIndex:26772; }","duration":"363.783892ms","start":"2026-10-03T03:24:52.163908Z","end":"2026-10-03T03:24:52.527692Z","steps":["trace[1101784545] 'read index received' (duration: 62.512036ms)","trace[1101784545] 'applied index is now lower than readState.Index' (duration: 301.270616ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:52.527816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.897715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:24:52.527898Z","caller":"traceutil/trace.go:171","msg":"trace[460776712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25934; }","duration":"364.003169ms","start":"2026-10-03T03:24:52.163858Z","end":"2026-10-03T03:24:52.527861Z","steps":["trace[460776712] 'agreement among raft nodes before linearized reading' (duration: 363.904066ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:52.527937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:52.163822Z","time spent":"364.107342ms","remote":"127.0.0.1:56246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T03:24:52.527947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.941979ms","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-10-03T03:24:52.527999Z","caller":"traceutil/trace.go:171","msg":"trace[1285434608] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25934; }","duration":"336.020021ms","start":"2026-10-03T03:24:52.191967Z","end":"2026-10-03T03:24:52.527987Z","steps":["trace[1285434608] 'agreement among raft nodes before linearized reading' (duration: 335.845206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:52.528043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:52.191956Z","time spent":"336.075782ms","remote":"127.0.0.1:56700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11859,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-03T03:24:52.528226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.873286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-10-03T03:24:52.528301Z","caller":"traceutil/trace.go:171","msg":"trace[434649881] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25934; }","duration":"102.98938ms","start":"2026-10-03T03:24:52.425294Z","end":"2026-10-03T03:24:52.528283Z","steps":["trace[434649881] 'agreement among raft nodes before linearized reading' (duration: 102.574879ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:52.86974Z","caller":"traceutil/trace.go:171","msg":"trace[1261013563] linearizableReadLoop","detail":"{readStateIndex:26778; appliedIndex:26775; }","duration":"331.725372ms","start":"2026-10-03T03:24:52.537989Z","end":"2026-10-03T03:24:52.869714Z","steps":["trace[1261013563] 'read index received' (duration: 86.508183ms)","trace[1261013563] 'applied index is now lower than readState.Index' (duration: 245.216089ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:52.869814Z","caller":"traceutil/trace.go:171","msg":"trace[909897881] transaction","detail":"{read_only:false; response_revision:25937; number_of_response:1; }","duration":"336.219686ms","start":"2026-10-03T03:24:52.533571Z","end":"2026-10-03T03:24:52.869791Z","steps":["trace[909897881] 'process raft request' (duration: 336.097693ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:52.869849Z","caller":"traceutil/trace.go:171","msg":"trace[1656446260] transaction","detail":"{read_only:false; response_revision:25936; number_of_response:1; }","duration":"336.321569ms","start":"2026-10-03T03:24:52.533452Z","end":"2026-10-03T03:24:52.869774Z","steps":["trace[1656446260] 'process raft request' (duration: 336.032361ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:52.869958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:52.533544Z","time spent":"336.332169ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:24:52.869992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.99397ms","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-10-03T03:24:52.870026Z","caller":"traceutil/trace.go:171","msg":"trace[114853565] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:25937; }","duration":"332.062812ms","start":"2026-10-03T03:24:52.537952Z","end":"2026-10-03T03:24:52.870015Z","steps":["trace[114853565] 'agreement among raft nodes before linearized reading' (duration: 331.908577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:52.870053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:52.537937Z","time spent":"332.108872ms","remote":"127.0.0.1:56700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11859,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-10-03T03:24:52.870055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:52.533423Z","time spent":"336.527755ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:24:52.87023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.479481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-03T03:24:52.870318Z","caller":"traceutil/trace.go:171","msg":"trace[1331384190] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25937; }","duration":"315.603583ms","start":"2026-10-03T03:24:52.554695Z","end":"2026-10-03T03:24:52.870298Z","steps":["trace[1331384190] 'agreement among raft nodes before linearized reading' (duration: 315.313065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:52.87036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:52.554681Z","time spent":"315.670866ms","remote":"127.0.0.1:56446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-10-03T03:24:53.285944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.961387ms","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-10-03T03:24:53.28605Z","caller":"traceutil/trace.go:171","msg":"trace[1748266768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25939; }","duration":"229.107771ms","start":"2026-10-03T03:24:53.056918Z","end":"2026-10-03T03:24:53.286026Z","steps":["trace[1748266768] 'range keys from in-memory index tree' (duration: 228.71382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:53.286698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.425675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:24:53.286931Z","caller":"traceutil/trace.go:171","msg":"trace[1871169026] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"228.796763ms","start":"2026-10-03T03:24:53.05812Z","end":"2026-10-03T03:24:53.286916Z","steps":["trace[1871169026] 'process raft request' (duration: 86.049159ms)","trace[1871169026] 'compare' (duration: 142.159088ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:53.286961Z","caller":"traceutil/trace.go:171","msg":"trace[911233228] linearizableReadLoop","detail":"{readStateIndex:26781; appliedIndex:26780; }","duration":"122.344187ms","start":"2026-10-03T03:24:53.164583Z","end":"2026-10-03T03:24:53.286928Z","steps":["trace[911233228] 'read index received' (duration: 78.983µs)","trace[911233228] 'applied index is now lower than readState.Index' (duration: 122.263134ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:53.287088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.513472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:24:53.287123Z","caller":"traceutil/trace.go:171","msg":"trace[778486481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25941; }","duration":"122.581784ms","start":"2026-10-03T03:24:53.164531Z","end":"2026-10-03T03:24:53.287113Z","steps":["trace[778486481] 'agreement among raft nodes before linearized reading' (duration: 122.519462ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:53.584856Z","caller":"traceutil/trace.go:171","msg":"trace[152749888] transaction","detail":"{read_only:false; response_revision:25943; number_of_response:1; }","duration":"293.517281ms","start":"2026-10-03T03:24:53.29132Z","end":"2026-10-03T03:24:53.584837Z","steps":["trace[152749888] 'process raft request' (duration: 287.054982ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:53.584846Z","caller":"traceutil/trace.go:171","msg":"trace[1447646242] linearizableReadLoop","detail":"{readStateIndex:26784; appliedIndex:26782; }","duration":"291.850194ms","start":"2026-10-03T03:24:53.292963Z","end":"2026-10-03T03:24:53.584813Z","steps":["trace[1447646242] 'read index received' (duration: 16.678863ms)","trace[1447646242] 'applied index is now lower than readState.Index' (duration: 275.170141ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:53.585655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.617296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:24:53.585777Z","caller":"traceutil/trace.go:171","msg":"trace[550368500] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25943; }","duration":"292.812841ms","start":"2026-10-03T03:24:53.292933Z","end":"2026-10-03T03:24:53.585746Z","steps":["trace[550368500] 'agreement among raft nodes before linearized reading' (duration: 291.964638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:53.946147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.171075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:24:53.946321Z","caller":"traceutil/trace.go:171","msg":"trace[2098052267] transaction","detail":"{read_only:false; response_revision:25945; number_of_response:1; }","duration":"287.671079ms","start":"2026-10-03T03:24:53.658636Z","end":"2026-10-03T03:24:53.946307Z","steps":["trace[2098052267] 'process raft request' (duration: 287.595987ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:53.946433Z","caller":"traceutil/trace.go:171","msg":"trace[945547726] transaction","detail":"{read_only:false; response_revision:25944; number_of_response:1; }","duration":"290.651981ms","start":"2026-10-03T03:24:53.655727Z","end":"2026-10-03T03:24:53.946379Z","steps":["trace[945547726] 'process raft request' (duration: 138.179347ms)","trace[945547726] 'compare' (duration: 151.837696ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:54.150667Z","caller":"traceutil/trace.go:171","msg":"trace[681475529] linearizableReadLoop","detail":"{readStateIndex:26787; appliedIndex:26786; }","duration":"204.357925ms","start":"2026-10-03T03:24:53.946278Z","end":"2026-10-03T03:24:54.150636Z","steps":["trace[681475529] 'read index received' (duration: 104.631615ms)","trace[681475529] 'applied index is now lower than readState.Index' (duration: 99.72518ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:54.150834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.167823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:24:54.150876Z","caller":"traceutil/trace.go:171","msg":"trace[739019782] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25946; }","duration":"247.296007ms","start":"2026-10-03T03:24:53.903565Z","end":"2026-10-03T03:24:54.150861Z","steps":["trace[739019782] 'agreement among raft nodes before linearized reading' (duration: 247.175944ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:54.151059Z","caller":"traceutil/trace.go:171","msg":"trace[1019377847] transaction","detail":"{read_only:false; response_revision:25946; number_of_response:1; }","duration":"294.341334ms","start":"2026-10-03T03:24:53.856656Z","end":"2026-10-03T03:24:54.150998Z","steps":["trace[1019377847] 'process raft request' (duration: 194.284445ms)","trace[1019377847] 'compare' (duration: 99.513814ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:54.530034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.815844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:24:54.530138Z","caller":"traceutil/trace.go:171","msg":"trace[841335572] linearizableReadLoop","detail":"{readStateIndex:26788; appliedIndex:26787; }","duration":"179.445173ms","start":"2026-10-03T03:24:54.350674Z","end":"2026-10-03T03:24:54.530119Z","steps":["trace[841335572] 'read index received' (duration: 22.461614ms)","trace[841335572] 'applied index is now lower than readState.Index' (duration: 156.981969ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:54.530501Z","caller":"traceutil/trace.go:171","msg":"trace[603650020] transaction","detail":"{read_only:false; response_revision:25947; number_of_response:1; }","duration":"296.959636ms","start":"2026-10-03T03:24:54.233504Z","end":"2026-10-03T03:24:54.530464Z","steps":["trace[603650020] 'process raft request' (duration: 139.619787ms)","trace[603650020] 'compare' (duration: 156.581888ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:54.531786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.114959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T03:24:54.531823Z","caller":"traceutil/trace.go:171","msg":"trace[1089479982] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25947; }","duration":"181.211223ms","start":"2026-10-03T03:24:54.350601Z","end":"2026-10-03T03:24:54.531812Z","steps":["trace[1089479982] 'agreement among raft nodes before linearized reading' (duration: 179.562847ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:54.838688Z","caller":"traceutil/trace.go:171","msg":"trace[811695621] linearizableReadLoop","detail":"{readStateIndex:26789; appliedIndex:26788; }","duration":"297.436078ms","start":"2026-10-03T03:24:54.54122Z","end":"2026-10-03T03:24:54.838656Z","steps":["trace[811695621] 'read index received' (duration: 286.815653ms)","trace[811695621] 'applied index is now lower than readState.Index' (duration: 10.619285ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:54.838877Z","caller":"traceutil/trace.go:171","msg":"trace[1137640441] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"303.675002ms","start":"2026-10-03T03:24:54.535189Z","end":"2026-10-03T03:24:54.838864Z","steps":["trace[1137640441] 'process raft request' (duration: 292.840301ms)","trace[1137640441] 'compare' (duration: 10.121991ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:54.838945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.420493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"warn","ts":"2026-10-03T03:24:54.838984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:24:54.53516Z","time spent":"303.751753ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:24:54.839003Z","caller":"traceutil/trace.go:171","msg":"trace[86874017] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25948; }","duration":"210.532366ms","start":"2026-10-03T03:24:54.628455Z","end":"2026-10-03T03:24:54.838987Z","steps":["trace[86874017] 'agreement among raft nodes before linearized reading' (duration: 210.374522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:54.839153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.929342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-03T03:24:54.83918Z","caller":"traceutil/trace.go:171","msg":"trace[2018688514] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25948; }","duration":"297.989823ms","start":"2026-10-03T03:24:54.54118Z","end":"2026-10-03T03:24:54.83917Z","steps":["trace[2018688514] 'agreement among raft nodes before linearized reading' (duration: 297.908221ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:54.839355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.474496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:180"} {"level":"info","ts":"2026-10-03T03:24:54.839396Z","caller":"traceutil/trace.go:171","msg":"trace[934747213] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:25948; }","duration":"205.579269ms","start":"2026-10-03T03:24:54.633806Z","end":"2026-10-03T03:24:54.839386Z","steps":["trace[934747213] 'agreement among raft nodes before linearized reading' (duration: 205.503347ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:55.069084Z","caller":"traceutil/trace.go:171","msg":"trace[1598568021] linearizableReadLoop","detail":"{readStateIndex:26790; appliedIndex:26789; }","duration":"193.961406ms","start":"2026-10-03T03:24:54.875089Z","end":"2026-10-03T03:24:55.069051Z","steps":["trace[1598568021] 'read index received' (duration: 122.901863ms)","trace[1598568021] 'applied index is now lower than readState.Index' (duration: 71.058133ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:24:55.069518Z","caller":"traceutil/trace.go:171","msg":"trace[362604926] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"225.05761ms","start":"2026-10-03T03:24:54.844414Z","end":"2026-10-03T03:24:55.069471Z","steps":["trace[362604926] 'process raft request' (duration: 153.633416ms)","trace[362604926] 'compare' (duration: 70.831187ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:24:55.069571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.447709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-03T03:24:55.0696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.539194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-10-03T03:24:55.069666Z","caller":"traceutil/trace.go:171","msg":"trace[1554711110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25949; }","duration":"194.598855ms","start":"2026-10-03T03:24:54.875047Z","end":"2026-10-03T03:24:55.069646Z","steps":["trace[1554711110] 'agreement among raft nodes before linearized reading' (duration: 194.309276ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:55.069695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.588621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:24:55.06972Z","caller":"traceutil/trace.go:171","msg":"trace[1290607821] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:25949; }","duration":"126.684948ms","start":"2026-10-03T03:24:54.943014Z","end":"2026-10-03T03:24:55.069699Z","steps":["trace[1290607821] 'agreement among raft nodes before linearized reading' (duration: 126.449891ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:24:55.069907Z","caller":"traceutil/trace.go:171","msg":"trace[1907775233] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25949; }","duration":"191.814537ms","start":"2026-10-03T03:24:54.87807Z","end":"2026-10-03T03:24:55.069884Z","steps":["trace[1907775233] 'agreement among raft nodes before linearized reading' (duration: 191.526089ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:55.069998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.053199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-03T03:24:55.070115Z","caller":"traceutil/trace.go:171","msg":"trace[1590495642] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25949; }","duration":"194.200493ms","start":"2026-10-03T03:24:54.875888Z","end":"2026-10-03T03:24:55.070088Z","steps":["trace[1590495642] 'agreement among raft nodes before linearized reading' (duration: 193.551795ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:24:55.069948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.846254ms","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-10-03T03:24:55.070352Z","caller":"traceutil/trace.go:171","msg":"trace[349264360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25949; }","duration":"122.215994ms","start":"2026-10-03T03:24:54.948041Z","end":"2026-10-03T03:24:55.070257Z","steps":["trace[349264360] 'agreement among raft nodes before linearized reading' (duration: 121.588477ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:02.09688Z","caller":"traceutil/trace.go:171","msg":"trace[412001755] linearizableReadLoop","detail":"{readStateIndex:26874; appliedIndex:26873; }","duration":"113.757279ms","start":"2026-10-03T03:25:01.983089Z","end":"2026-10-03T03:25:02.096846Z","steps":["trace[412001755] 'read index received' (duration: 113.495672ms)","trace[412001755] 'applied index is now lower than readState.Index' (duration: 260.067µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:02.096921Z","caller":"traceutil/trace.go:171","msg":"trace[320481142] transaction","detail":"{read_only:false; response_revision:26032; number_of_response:1; }","duration":"122.390099ms","start":"2026-10-03T03:25:01.974494Z","end":"2026-10-03T03:25:02.096884Z","steps":["trace[320481142] 'process raft request' (duration: 122.152063ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:02.097188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.071038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T03:25:02.097364Z","caller":"traceutil/trace.go:171","msg":"trace[1958777640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26032; }","duration":"114.290313ms","start":"2026-10-03T03:25:01.983035Z","end":"2026-10-03T03:25:02.097325Z","steps":["trace[1958777640] 'agreement among raft nodes before linearized reading' (duration: 113.918943ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:03.443204Z","caller":"traceutil/trace.go:171","msg":"trace[1383375386] transaction","detail":"{read_only:false; response_revision:26043; number_of_response:1; }","duration":"120.503087ms","start":"2026-10-03T03:25:03.322677Z","end":"2026-10-03T03:25:03.44318Z","steps":["trace[1383375386] 'process raft request' (duration: 120.360533ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:03.447745Z","caller":"traceutil/trace.go:171","msg":"trace[259286692] transaction","detail":"{read_only:false; response_revision:26044; number_of_response:1; }","duration":"108.260437ms","start":"2026-10-03T03:25:03.339442Z","end":"2026-10-03T03:25:03.447703Z","steps":["trace[259286692] 'process raft request' (duration: 106.076466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:03.746189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.413825ms","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":"warn","ts":"2026-10-03T03:25:03.746478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.445793ms","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-10-03T03:25:03.746581Z","caller":"traceutil/trace.go:171","msg":"trace[51339194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26045; }","duration":"103.610387ms","start":"2026-10-03T03:25:03.642944Z","end":"2026-10-03T03:25:03.746555Z","steps":["trace[51339194] 'range keys from in-memory index tree' (duration: 103.182416ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:03.746518Z","caller":"traceutil/trace.go:171","msg":"trace[1560535587] 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:26045; }","duration":"133.771905ms","start":"2026-10-03T03:25:03.612721Z","end":"2026-10-03T03:25:03.746493Z","steps":["trace[1560535587] 'range keys from in-memory index tree' (duration: 133.306212ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:03.978982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.220336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:03.979126Z","caller":"traceutil/trace.go:171","msg":"trace[1352439576] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26046; }","duration":"130.429862ms","start":"2026-10-03T03:25:03.848674Z","end":"2026-10-03T03:25:03.979104Z","steps":["trace[1352439576] 'range keys from in-memory index tree' (duration: 129.838116ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:04.214016Z","caller":"traceutil/trace.go:171","msg":"trace[53432112] linearizableReadLoop","detail":"{readStateIndex:26890; appliedIndex:26889; }","duration":"106.538888ms","start":"2026-10-03T03:25:04.107454Z","end":"2026-10-03T03:25:04.213993Z","steps":["trace[53432112] 'read index received' (duration: 106.432825ms)","trace[53432112] 'applied index is now lower than readState.Index' (duration: 104.893µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:04.214096Z","caller":"traceutil/trace.go:171","msg":"trace[1257336655] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"111.182377ms","start":"2026-10-03T03:25:04.102861Z","end":"2026-10-03T03:25:04.214043Z","steps":["trace[1257336655] 'process raft request' (duration: 110.889579ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:04.214241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.777365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T03:25:04.214287Z","caller":"traceutil/trace.go:171","msg":"trace[1568515803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26047; }","duration":"106.888228ms","start":"2026-10-03T03:25:04.107381Z","end":"2026-10-03T03:25:04.21427Z","steps":["trace[1568515803] 'agreement among raft nodes before linearized reading' (duration: 106.740864ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:04.366993Z","caller":"traceutil/trace.go:171","msg":"trace[1430034703] linearizableReadLoop","detail":"{readStateIndex:26891; appliedIndex:26890; }","duration":"152.883115ms","start":"2026-10-03T03:25:04.214083Z","end":"2026-10-03T03:25:04.366967Z","steps":["trace[1430034703] 'read index received' (duration: 152.70792ms)","trace[1430034703] 'applied index is now lower than readState.Index' (duration: 173.925µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:04.367098Z","caller":"traceutil/trace.go:171","msg":"trace[1199382044] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"170.237657ms","start":"2026-10-03T03:25:04.196824Z","end":"2026-10-03T03:25:04.367061Z","steps":["trace[1199382044] 'process raft request' (duration: 169.972729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:04.367147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.283357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:04.3672Z","caller":"traceutil/trace.go:171","msg":"trace[785447655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26048; }","duration":"202.39415ms","start":"2026-10-03T03:25:04.16479Z","end":"2026-10-03T03:25:04.367185Z","steps":["trace[785447655] 'agreement among raft nodes before linearized reading' (duration: 202.287437ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:04.377146Z","caller":"traceutil/trace.go:171","msg":"trace[2131132252] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"157.437372ms","start":"2026-10-03T03:25:04.219688Z","end":"2026-10-03T03:25:04.377125Z","steps":["trace[2131132252] 'process raft request' (duration: 157.231796ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:04.694071Z","caller":"traceutil/trace.go:171","msg":"trace[1307396519] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"117.649906ms","start":"2026-10-03T03:25:04.576383Z","end":"2026-10-03T03:25:04.694033Z","steps":["trace[1307396519] 'process raft request' (duration: 116.943787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:05.223178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.390636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-03T03:25:05.223266Z","caller":"traceutil/trace.go:171","msg":"trace[1527466087] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26051; }","duration":"101.54726ms","start":"2026-10-03T03:25:05.121697Z","end":"2026-10-03T03:25:05.223245Z","steps":["trace[1527466087] 'range keys from in-memory index tree' (duration: 101.078007ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:05.401866Z","caller":"traceutil/trace.go:171","msg":"trace[169648941] transaction","detail":"{read_only:false; response_revision:26053; number_of_response:1; }","duration":"173.282131ms","start":"2026-10-03T03:25:05.228524Z","end":"2026-10-03T03:25:05.401806Z","steps":["trace[169648941] 'process raft request' (duration: 173.202159ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:05.401944Z","caller":"traceutil/trace.go:171","msg":"trace[126334875] transaction","detail":"{read_only:false; response_revision:26052; number_of_response:1; }","duration":"173.374424ms","start":"2026-10-03T03:25:05.228524Z","end":"2026-10-03T03:25:05.401899Z","steps":["trace[126334875] 'process raft request' (duration: 172.944552ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:05.522896Z","caller":"traceutil/trace.go:171","msg":"trace[2041956407] transaction","detail":"{read_only:false; response_revision:26054; number_of_response:1; }","duration":"294.058725ms","start":"2026-10-03T03:25:05.228796Z","end":"2026-10-03T03:25:05.522855Z","steps":["trace[2041956407] 'process raft request' (duration: 291.832053ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:05.522962Z","caller":"traceutil/trace.go:171","msg":"trace[295923936] transaction","detail":"{read_only:false; response_revision:26055; number_of_response:1; }","duration":"284.591512ms","start":"2026-10-03T03:25:05.238342Z","end":"2026-10-03T03:25:05.522934Z","steps":["trace[295923936] 'process raft request' (duration: 284.406157ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:05.672321Z","caller":"traceutil/trace.go:171","msg":"trace[876085468] transaction","detail":"{read_only:false; response_revision:26058; number_of_response:1; }","duration":"142.888268ms","start":"2026-10-03T03:25:05.5294Z","end":"2026-10-03T03:25:05.672288Z","steps":["trace[876085468] 'process raft request' (duration: 142.416615ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:08.655898Z","caller":"traceutil/trace.go:171","msg":"trace[772801221] linearizableReadLoop","detail":"{readStateIndex:26936; appliedIndex:26935; }","duration":"169.60831ms","start":"2026-10-03T03:25:08.486253Z","end":"2026-10-03T03:25:08.655861Z","steps":["trace[772801221] 'read index received' (duration: 136.47619ms)","trace[772801221] 'applied index is now lower than readState.Index' (duration: 33.13073ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:08.656061Z","caller":"traceutil/trace.go:171","msg":"trace[293087714] transaction","detail":"{read_only:false; response_revision:26092; number_of_response:1; }","duration":"172.063098ms","start":"2026-10-03T03:25:08.483983Z","end":"2026-10-03T03:25:08.656046Z","steps":["trace[293087714] 'process raft request' (duration: 138.803785ms)","trace[293087714] 'compare' (duration: 32.920454ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:08.656438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.160615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-03T03:25:08.656491Z","caller":"traceutil/trace.go:171","msg":"trace[128951134] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26092; }","duration":"170.245008ms","start":"2026-10-03T03:25:08.486233Z","end":"2026-10-03T03:25:08.656478Z","steps":["trace[128951134] 'agreement among raft nodes before linearized reading' (duration: 170.065123ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:08.937868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.51518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:08.937997Z","caller":"traceutil/trace.go:171","msg":"trace[1748228104] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"217.362674ms","start":"2026-10-03T03:25:08.720608Z","end":"2026-10-03T03:25:08.937971Z","steps":["trace[1748228104] 'process raft request' (duration: 71.624589ms)","trace[1748228104] 'compare' (duration: 145.378135ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:11.27194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.058473ms","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-10-03T03:25:11.272027Z","caller":"traceutil/trace.go:171","msg":"trace[1694282110] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:26112; }","duration":"161.202067ms","start":"2026-10-03T03:25:11.110804Z","end":"2026-10-03T03:25:11.272006Z","steps":["trace[1694282110] 'count revisions from in-memory index tree' (duration: 160.98059ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:11.272007Z","caller":"traceutil/trace.go:171","msg":"trace[1985780962] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"151.23661ms","start":"2026-10-03T03:25:11.120735Z","end":"2026-10-03T03:25:11.271971Z","steps":["trace[1985780962] 'process raft request' (duration: 131.633295ms)","trace[1985780962] 'compare' (duration: 19.194503ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:11.596348Z","caller":"traceutil/trace.go:171","msg":"trace[1631611724] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"161.151225ms","start":"2026-10-03T03:25:11.435164Z","end":"2026-10-03T03:25:11.596315Z","steps":["trace[1631611724] 'process raft request' (duration: 160.96098ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:11.700734Z","caller":"traceutil/trace.go:171","msg":"trace[1461407996] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"264.32546ms","start":"2026-10-03T03:25:11.436379Z","end":"2026-10-03T03:25:11.700704Z","steps":["trace[1461407996] 'process raft request' (duration: 172.568002ms)","trace[1461407996] 'compare' (duration: 91.549842ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:11.701027Z","caller":"traceutil/trace.go:171","msg":"trace[327769053] linearizableReadLoop","detail":"{readStateIndex:26961; appliedIndex:26957; }","duration":"148.304408ms","start":"2026-10-03T03:25:11.552691Z","end":"2026-10-03T03:25:11.700995Z","steps":["trace[327769053] 'read index received' (duration: 43.434676ms)","trace[327769053] 'applied index is now lower than readState.Index' (duration: 104.868442ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:11.701164Z","caller":"traceutil/trace.go:171","msg":"trace[544608148] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"151.112966ms","start":"2026-10-03T03:25:11.550039Z","end":"2026-10-03T03:25:11.701152Z","steps":["trace[544608148] 'process raft request' (duration: 150.868709ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:11.701193Z","caller":"traceutil/trace.go:171","msg":"trace[310697165] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"154.945632ms","start":"2026-10-03T03:25:11.546204Z","end":"2026-10-03T03:25:11.70115Z","steps":["trace[310697165] 'process raft request' (duration: 154.461879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:11.701421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.70969ms","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-10-03T03:25:11.70146Z","caller":"traceutil/trace.go:171","msg":"trace[1273509230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26117; }","duration":"148.786252ms","start":"2026-10-03T03:25:11.552665Z","end":"2026-10-03T03:25:11.701451Z","steps":["trace[1273509230] 'agreement among raft nodes before linearized reading' (duration: 148.615067ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:11.70167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.925328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-03T03:25:11.701718Z","caller":"traceutil/trace.go:171","msg":"trace[2064996518] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26117; }","duration":"148.00243ms","start":"2026-10-03T03:25:11.553702Z","end":"2026-10-03T03:25:11.701704Z","steps":["trace[2064996518] 'agreement among raft nodes before linearized reading' (duration: 147.817544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.328399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.143787ms","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-10-03T03:25:14.328526Z","caller":"traceutil/trace.go:171","msg":"trace[1296221437] 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:26192; }","duration":"330.330883ms","start":"2026-10-03T03:25:13.998165Z","end":"2026-10-03T03:25:14.328496Z","steps":["trace[1296221437] 'range keys from in-memory index tree' (duration: 330.060715ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.328604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:13.998153Z","time spent":"330.437556ms","remote":"127.0.0.1:52332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-10-03T03:25:14.328559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.238726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-10-03T03:25:14.328705Z","caller":"traceutil/trace.go:171","msg":"trace[582916663] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:26192; }","duration":"368.38816ms","start":"2026-10-03T03:25:13.960279Z","end":"2026-10-03T03:25:14.328667Z","steps":["trace[582916663] 'range keys from in-memory index tree' (duration: 368.085251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.32875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:13.960255Z","time spent":"368.485603ms","remote":"127.0.0.1:56346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-10-03T03:25:14.52154Z","caller":"traceutil/trace.go:171","msg":"trace[697114911] transaction","detail":"{read_only:false; response_revision:26193; number_of_response:1; }","duration":"481.558792ms","start":"2026-10-03T03:25:14.039957Z","end":"2026-10-03T03:25:14.521516Z","steps":["trace[697114911] 'process raft request' (duration: 481.403328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.521711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:14.039933Z","time spent":"481.700896ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5702,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:14.577012Z","caller":"traceutil/trace.go:171","msg":"trace[1572840703] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"521.660736ms","start":"2026-10-03T03:25:14.055322Z","end":"2026-10-03T03:25:14.576983Z","steps":["trace[1572840703] 'process raft request' (duration: 521.42708ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.577311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:14.055303Z","time spent":"521.913953ms","remote":"127.0.0.1:56482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5256,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:14.577496Z","caller":"traceutil/trace.go:171","msg":"trace[423419099] transaction","detail":"{read_only:false; response_revision:26195; number_of_response:1; }","duration":"324.036068ms","start":"2026-10-03T03:25:14.253421Z","end":"2026-10-03T03:25:14.577457Z","steps":["trace[423419099] 'process raft request' (duration: 323.504944ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:14.577519Z","caller":"traceutil/trace.go:171","msg":"trace[1643602058] linearizableReadLoop","detail":"{readStateIndex:27039; appliedIndex:27037; }","duration":"413.460211ms","start":"2026-10-03T03:25:14.164033Z","end":"2026-10-03T03:25:14.577493Z","steps":["trace[1643602058] 'read index received' (duration: 357.417395ms)","trace[1643602058] 'applied index is now lower than readState.Index' (duration: 56.041476ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:14.577687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.632826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T03:25:14.577703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:14.253391Z","time spent":"324.205573ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:14.577731Z","caller":"traceutil/trace.go:171","msg":"trace[1885582229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26196; }","duration":"413.735859ms","start":"2026-10-03T03:25:14.163981Z","end":"2026-10-03T03:25:14.577717Z","steps":["trace[1885582229] 'agreement among raft nodes before linearized reading' (duration: 413.646176ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.577763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:14.163944Z","time spent":"413.811431ms","remote":"127.0.0.1:56246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T03:25:14.579826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.082078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:14.579878Z","caller":"traceutil/trace.go:171","msg":"trace[1249640108] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26197; }","duration":"244.192471ms","start":"2026-10-03T03:25:14.335672Z","end":"2026-10-03T03:25:14.579865Z","steps":["trace[1249640108] 'agreement among raft nodes before linearized reading' (duration: 244.095939ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.579917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.892882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eb87d1a8-27a2-4db4-8350-9575a87afcda\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-10-03T03:25:14.579968Z","caller":"traceutil/trace.go:171","msg":"trace[1634179965] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eb87d1a8-27a2-4db4-8350-9575a87afcda; range_end:; response_count:1; response_revision:26197; }","duration":"185.996925ms","start":"2026-10-03T03:25:14.393957Z","end":"2026-10-03T03:25:14.579954Z","steps":["trace[1634179965] 'agreement among raft nodes before linearized reading' (duration: 185.804169ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.580367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.937967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:14.580412Z","caller":"traceutil/trace.go:171","msg":"trace[586092016] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:26197; }","duration":"247.019719ms","start":"2026-10-03T03:25:14.333382Z","end":"2026-10-03T03:25:14.580401Z","steps":["trace[586092016] 'agreement among raft nodes before linearized reading' (duration: 246.949817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.582276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.937887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T03:25:14.582347Z","caller":"traceutil/trace.go:171","msg":"trace[830112202] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26197; }","duration":"310.05832ms","start":"2026-10-03T03:25:14.272274Z","end":"2026-10-03T03:25:14.582333Z","steps":["trace[830112202] 'agreement among raft nodes before linearized reading' (duration: 307.505439ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:14.582379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:14.272257Z","time spent":"310.113452ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2804,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-10-03T03:25:17.486767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.240225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:17.48684Z","caller":"traceutil/trace.go:171","msg":"trace[33149566] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"193.542515ms","start":"2026-10-03T03:25:17.293284Z","end":"2026-10-03T03:25:17.486826Z","steps":["trace[33149566] 'process raft request' (duration: 67.185066ms)","trace[33149566] 'compare' (duration: 125.919546ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:20.710999Z","caller":"traceutil/trace.go:171","msg":"trace[2144850791] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"112.488644ms","start":"2026-10-03T03:25:20.598483Z","end":"2026-10-03T03:25:20.710971Z","steps":["trace[2144850791] 'process raft request' (duration: 112.312689ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:20.884309Z","caller":"traceutil/trace.go:171","msg":"trace[544409217] linearizableReadLoop","detail":"{readStateIndex:27124; appliedIndex:27123; }","duration":"164.621031ms","start":"2026-10-03T03:25:20.719657Z","end":"2026-10-03T03:25:20.884278Z","steps":["trace[544409217] 'read index received' (duration: 119.368184ms)","trace[544409217] 'applied index is now lower than readState.Index' (duration: 45.249007ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:20.88432Z","caller":"traceutil/trace.go:171","msg":"trace[1358393060] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"284.084139ms","start":"2026-10-03T03:25:20.600201Z","end":"2026-10-03T03:25:20.884285Z","steps":["trace[1358393060] 'process raft request' (duration: 238.894423ms)","trace[1358393060] 'compare' (duration: 44.927348ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:20.884569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.858978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T03:25:20.884614Z","caller":"traceutil/trace.go:171","msg":"trace[1918587446] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26278; }","duration":"164.997742ms","start":"2026-10-03T03:25:20.719601Z","end":"2026-10-03T03:25:20.884599Z","steps":["trace[1918587446] 'agreement among raft nodes before linearized reading' (duration: 164.777706ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:21.062624Z","caller":"traceutil/trace.go:171","msg":"trace[2012829423] transaction","detail":"{read_only:false; response_revision:26279; number_of_response:1; }","duration":"172.51546ms","start":"2026-10-03T03:25:20.890074Z","end":"2026-10-03T03:25:21.062589Z","steps":["trace[2012829423] 'process raft request' (duration: 120.28597ms)","trace[2012829423] 'compare' (duration: 51.926012ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:51.831347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.617714ms","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-10-03T03:25:51.831471Z","caller":"traceutil/trace.go:171","msg":"trace[1584643830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26529; }","duration":"597.764168ms","start":"2026-10-03T03:25:51.233658Z","end":"2026-10-03T03:25:51.831422Z","steps":["trace[1584643830] 'range keys from in-memory index tree' (duration: 597.4502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:51.831521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.233641Z","time spent":"597.869181ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-03T03:25:51.832394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.833961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T03:25:51.83251Z","caller":"traceutil/trace.go:171","msg":"trace[2050964330] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26529; }","duration":"481.000426ms","start":"2026-10-03T03:25:51.35148Z","end":"2026-10-03T03:25:51.83248Z","steps":["trace[2050964330] 'count revisions from in-memory index tree' (duration: 480.766468ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:51.832556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.351462Z","time spent":"481.082897ms","remote":"127.0.0.1:35814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-03T03:25:51.833002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.246787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:51.833219Z","caller":"traceutil/trace.go:171","msg":"trace[992110844] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"220.668227ms","start":"2026-10-03T03:25:51.612534Z","end":"2026-10-03T03:25:51.833202Z","steps":["trace[992110844] 'process raft request' (duration: 95.153062ms)","trace[992110844] 'compare' (duration: 124.302061ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:52.063366Z","caller":"traceutil/trace.go:171","msg":"trace[803538071] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"224.619118ms","start":"2026-10-03T03:25:51.838702Z","end":"2026-10-03T03:25:52.063321Z","steps":["trace[803538071] 'process raft request' (duration: 151.705583ms)","trace[803538071] 'compare' (duration: 72.465462ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:52.39516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.707458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:52.39543Z","caller":"traceutil/trace.go:171","msg":"trace[677256567] transaction","detail":"{read_only:false; response_revision:26532; number_of_response:1; }","duration":"555.789924ms","start":"2026-10-03T03:25:51.839592Z","end":"2026-10-03T03:25:52.395382Z","steps":["trace[677256567] 'process raft request' (duration: 419.781287ms)","trace[677256567] 'compare' (duration: 135.365259ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:52.395475Z","caller":"traceutil/trace.go:171","msg":"trace[2043739649] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"543.012739ms","start":"2026-10-03T03:25:51.8524Z","end":"2026-10-03T03:25:52.395413Z","steps":["trace[2043739649] 'process raft request' (duration: 542.847924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:52.395598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.839563Z","time spent":"555.952149ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:25:52.39563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.852383Z","time spent":"543.164313ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:52.395678Z","caller":"traceutil/trace.go:171","msg":"trace[345977719] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"540.18069ms","start":"2026-10-03T03:25:51.855471Z","end":"2026-10-03T03:25:52.395652Z","steps":["trace[345977719] 'process raft request' (duration: 539.828131ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:52.395758Z","caller":"traceutil/trace.go:171","msg":"trace[1117303384] linearizableReadLoop","detail":"{readStateIndex:27387; appliedIndex:27383; }","duration":"487.002544ms","start":"2026-10-03T03:25:51.908747Z","end":"2026-10-03T03:25:52.395749Z","steps":["trace[1117303384] 'read index received' (duration: 81.674108ms)","trace[1117303384] 'applied index is now lower than readState.Index' (duration: 405.326926ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:52.39577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.855433Z","time spent":"540.290493ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:25:52.637551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.180485ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-03T03:25:52.637682Z","caller":"traceutil/trace.go:171","msg":"trace[1488374561] linearizableReadLoop","detail":"{readStateIndex:27388; appliedIndex:27387; }","duration":"241.886777ms","start":"2026-10-03T03:25:52.395773Z","end":"2026-10-03T03:25:52.63766Z","steps":["trace[1488374561] 'read index received' (duration: 50.721µs)","trace[1488374561] 'applied index is now lower than readState.Index' (duration: 241.834446ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:52.638031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.457693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:52.638071Z","caller":"traceutil/trace.go:171","msg":"trace[336020038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26534; }","duration":"466.547466ms","start":"2026-10-03T03:25:52.171511Z","end":"2026-10-03T03:25:52.638059Z","steps":["trace[336020038] 'agreement among raft nodes before linearized reading' (duration: 466.455623ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:52.6381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.171493Z","time spent":"466.599627ms","remote":"127.0.0.1:56246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-03T03:25:52.638187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.404405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-10-03T03:25:52.638285Z","caller":"traceutil/trace.go:171","msg":"trace[1350691142] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26534; }","duration":"729.546259ms","start":"2026-10-03T03:25:51.908705Z","end":"2026-10-03T03:25:52.638251Z","steps":["trace[1350691142] 'agreement among raft nodes before linearized reading' (duration: 487.105307ms)","trace[1350691142] 'range keys from in-memory index tree' (duration: 242.169905ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:52.638328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.908688Z","time spent":"729.630531ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-10-03T03:25:52.638348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.145796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-03T03:25:52.638477Z","caller":"traceutil/trace.go:171","msg":"trace[115898523] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26534; }","duration":"667.30056ms","start":"2026-10-03T03:25:51.971122Z","end":"2026-10-03T03:25:52.638422Z","steps":["trace[115898523] 'agreement among raft nodes before linearized reading' (duration: 667.030912ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:52.638531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.9711Z","time spent":"667.420074ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-03T03:25:52.638558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.601491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-03T03:25:52.638633Z","caller":"traceutil/trace.go:171","msg":"trace[2045054220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26534; }","duration":"630.733365ms","start":"2026-10-03T03:25:52.007882Z","end":"2026-10-03T03:25:52.638615Z","steps":["trace[2045054220] 'agreement among raft nodes before linearized reading' (duration: 630.472348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:52.638668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.007864Z","time spent":"630.797736ms","remote":"127.0.0.1:56500","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-10-03T03:25:52.638768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.744173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T03:25:52.63881Z","caller":"traceutil/trace.go:171","msg":"trace[241168378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26534; }","duration":"568.818935ms","start":"2026-10-03T03:25:52.069977Z","end":"2026-10-03T03:25:52.638796Z","steps":["trace[241168378] 'agreement among raft nodes before linearized reading' (duration: 568.702612ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:52.638841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.069961Z","time spent":"568.871317ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-03T03:25:52.638851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.789622ms","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-10-03T03:25:52.638889Z","caller":"traceutil/trace.go:171","msg":"trace[2078766969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26534; }","duration":"641.858674ms","start":"2026-10-03T03:25:51.99702Z","end":"2026-10-03T03:25:52.638878Z","steps":["trace[2078766969] 'agreement among raft nodes before linearized reading' (duration: 641.72107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:52.638916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:51.997003Z","time spent":"641.906435ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-03T03:25:52.771074Z","caller":"traceutil/trace.go:171","msg":"trace[756623136] transaction","detail":"{read_only:false; response_revision:26535; number_of_response:1; }","duration":"125.758442ms","start":"2026-10-03T03:25:52.645279Z","end":"2026-10-03T03:25:52.771037Z","steps":["trace[756623136] 'process raft request' (duration: 125.541936ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:53.045143Z","caller":"traceutil/trace.go:171","msg":"trace[324238082] transaction","detail":"{read_only:false; response_revision:26536; number_of_response:1; }","duration":"399.820162ms","start":"2026-10-03T03:25:52.645281Z","end":"2026-10-03T03:25:53.045102Z","steps":["trace[324238082] 'process raft request' (duration: 317.034494ms)","trace[324238082] 'compare' (duration: 81.510713ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:53.045344Z","caller":"traceutil/trace.go:171","msg":"trace[1546757445] transaction","detail":"{read_only:false; response_revision:26537; number_of_response:1; }","duration":"400.031118ms","start":"2026-10-03T03:25:52.645279Z","end":"2026-10-03T03:25:53.04531Z","steps":["trace[1546757445] 'process raft request' (duration: 399.689239ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:53.045465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.645263Z","time spent":"399.957957ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:53.045436Z","caller":"traceutil/trace.go:171","msg":"trace[1592391685] transaction","detail":"{read_only:false; response_revision:26538; number_of_response:1; }","duration":"400.040639ms","start":"2026-10-03T03:25:52.645353Z","end":"2026-10-03T03:25:53.045394Z","steps":["trace[1592391685] 'process raft request' (duration: 399.71256ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:53.045621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.645347Z","time spent":"400.185602ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:25:53.045622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.645245Z","time spent":"400.224284ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:53.045864Z","caller":"traceutil/trace.go:171","msg":"trace[1481908270] transaction","detail":"{read_only:false; response_revision:26539; number_of_response:1; }","duration":"397.538989ms","start":"2026-10-03T03:25:52.648311Z","end":"2026-10-03T03:25:53.04585Z","steps":["trace[1481908270] 'process raft request' (duration: 396.804099ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:53.04593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.648297Z","time spent":"397.600411ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:53.046331Z","caller":"traceutil/trace.go:171","msg":"trace[703413090] transaction","detail":"{read_only:false; response_revision:26540; number_of_response:1; }","duration":"348.348973ms","start":"2026-10-03T03:25:52.697964Z","end":"2026-10-03T03:25:53.046313Z","steps":["trace[703413090] 'process raft request' (duration: 347.904741ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:53.046376Z","caller":"traceutil/trace.go:171","msg":"trace[137833520] transaction","detail":"{read_only:false; response_revision:26541; number_of_response:1; }","duration":"215.428462ms","start":"2026-10-03T03:25:52.830914Z","end":"2026-10-03T03:25:53.046342Z","steps":["trace[137833520] 'process raft request' (duration: 215.155484ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:53.046533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:52.697932Z","time spent":"348.489237ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:25:53.329264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.045173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:53.329366Z","caller":"traceutil/trace.go:171","msg":"trace[72062478] linearizableReadLoop","detail":"{readStateIndex:27397; appliedIndex:27396; }","duration":"159.244263ms","start":"2026-10-03T03:25:53.170104Z","end":"2026-10-03T03:25:53.329348Z","steps":["trace[72062478] 'read index received' (duration: 21.050775ms)","trace[72062478] 'applied index is now lower than readState.Index' (duration: 138.192328ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:53.329462Z","caller":"traceutil/trace.go:171","msg":"trace[2140589806] transaction","detail":"{read_only:false; response_revision:26543; number_of_response:1; }","duration":"277.376552ms","start":"2026-10-03T03:25:53.052056Z","end":"2026-10-03T03:25:53.329432Z","steps":["trace[2140589806] 'process raft request' (duration: 139.073501ms)","trace[2140589806] 'compare' (duration: 137.699634ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:53.329623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.51978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:53.329656Z","caller":"traceutil/trace.go:171","msg":"trace[1044769175] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26543; }","duration":"159.597382ms","start":"2026-10-03T03:25:53.170048Z","end":"2026-10-03T03:25:53.329645Z","steps":["trace[1044769175] 'agreement among raft nodes before linearized reading' (duration: 159.53729ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:53.399986Z","caller":"traceutil/trace.go:171","msg":"trace[1065182729] transaction","detail":"{read_only:false; response_revision:26544; number_of_response:1; }","duration":"220.873714ms","start":"2026-10-03T03:25:53.179059Z","end":"2026-10-03T03:25:53.399933Z","steps":["trace[1065182729] 'process raft request' (duration: 220.661208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:53.40018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.32293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T03:25:53.400263Z","caller":"traceutil/trace.go:171","msg":"trace[588599969] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:26544; }","duration":"107.475444ms","start":"2026-10-03T03:25:53.292768Z","end":"2026-10-03T03:25:53.400244Z","steps":["trace[588599969] 'agreement among raft nodes before linearized reading' (duration: 107.097574ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:54.197622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.962459ms","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-10-03T03:25:54.19768Z","caller":"traceutil/trace.go:171","msg":"trace[1039164390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26545; }","duration":"130.071812ms","start":"2026-10-03T03:25:54.067594Z","end":"2026-10-03T03:25:54.197666Z","steps":["trace[1039164390] 'range keys from in-memory index tree' (duration: 129.823695ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:54.356738Z","caller":"traceutil/trace.go:171","msg":"trace[40663972] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"154.436988ms","start":"2026-10-03T03:25:54.202271Z","end":"2026-10-03T03:25:54.356708Z","steps":["trace[40663972] 'process raft request' (duration: 154.213932ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:54.769392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.029744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:54.76953Z","caller":"traceutil/trace.go:171","msg":"trace[549660938] linearizableReadLoop","detail":"{readStateIndex:27403; appliedIndex:27402; }","duration":"165.730272ms","start":"2026-10-03T03:25:54.60378Z","end":"2026-10-03T03:25:54.76951Z","steps":["trace[549660938] 'read index received' (duration: 50.487102ms)","trace[549660938] 'applied index is now lower than readState.Index' (duration: 115.24164ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:54.769627Z","caller":"traceutil/trace.go:171","msg":"trace[1520044804] transaction","detail":"{read_only:false; response_revision:26549; number_of_response:1; }","duration":"316.255232ms","start":"2026-10-03T03:25:54.453356Z","end":"2026-10-03T03:25:54.769611Z","steps":["trace[1520044804] 'process raft request' (duration: 200.912329ms)","trace[1520044804] 'compare' (duration: 114.714545ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:54.769689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:54.453326Z","time spent":"316.329214ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:25:54.769956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.174875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:54.769999Z","caller":"traceutil/trace.go:171","msg":"trace[1448089822] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:26549; }","duration":"166.272577ms","start":"2026-10-03T03:25:54.603712Z","end":"2026-10-03T03:25:54.769985Z","steps":["trace[1448089822] 'agreement among raft nodes before linearized reading' (duration: 166.189305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:54.770192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.490393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-03T03:25:54.770272Z","caller":"traceutil/trace.go:171","msg":"trace[1635973732] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26549; }","duration":"112.641898ms","start":"2026-10-03T03:25:54.657611Z","end":"2026-10-03T03:25:54.770253Z","steps":["trace[1635973732] 'agreement among raft nodes before linearized reading' (duration: 112.38293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.067572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.840763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:55.067687Z","caller":"traceutil/trace.go:171","msg":"trace[1049052222] linearizableReadLoop","detail":"{readStateIndex:27404; appliedIndex:27403; }","duration":"280.988292ms","start":"2026-10-03T03:25:54.786681Z","end":"2026-10-03T03:25:55.06767Z","steps":["trace[1049052222] 'read index received' (duration: 158.898462ms)","trace[1049052222] 'applied index is now lower than readState.Index' (duration: 122.08831ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:55.067877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.175728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:55.067948Z","caller":"traceutil/trace.go:171","msg":"trace[203395986] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26550; }","duration":"281.274711ms","start":"2026-10-03T03:25:54.786659Z","end":"2026-10-03T03:25:55.067933Z","steps":["trace[203395986] 'agreement among raft nodes before linearized reading' (duration: 281.159818ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:55.068255Z","caller":"traceutil/trace.go:171","msg":"trace[1801772130] transaction","detail":"{read_only:false; response_revision:26550; number_of_response:1; }","duration":"282.303079ms","start":"2026-10-03T03:25:54.785509Z","end":"2026-10-03T03:25:55.067812Z","steps":["trace[1801772130] 'process raft request' (duration: 160.134297ms)","trace[1801772130] 'compare' (duration: 121.535135ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:55.301011Z","caller":"traceutil/trace.go:171","msg":"trace[2095698485] linearizableReadLoop","detail":"{readStateIndex:27406; appliedIndex:27404; }","duration":"233.247007ms","start":"2026-10-03T03:25:55.067713Z","end":"2026-10-03T03:25:55.30096Z","steps":["trace[2095698485] 'read index received' (duration: 18.891494ms)","trace[2095698485] 'applied index is now lower than readState.Index' (duration: 214.351973ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:55.301155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.315648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"} {"level":"info","ts":"2026-10-03T03:25:55.301153Z","caller":"traceutil/trace.go:171","msg":"trace[1862983985] transaction","detail":"{read_only:false; response_revision:26552; number_of_response:1; }","duration":"240.234931ms","start":"2026-10-03T03:25:55.060879Z","end":"2026-10-03T03:25:55.301114Z","steps":["trace[1862983985] 'process raft request' (duration: 159.88104ms)","trace[1862983985] 'compare' (duration: 79.828006ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:55.301238Z","caller":"traceutil/trace.go:171","msg":"trace[1210653609] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26552; }","duration":"247.428401ms","start":"2026-10-03T03:25:55.05379Z","end":"2026-10-03T03:25:55.301218Z","steps":["trace[1210653609] 'agreement among raft nodes before linearized reading' (duration: 247.207865ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.30147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.541342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-03T03:25:55.301504Z","caller":"traceutil/trace.go:171","msg":"trace[1870473554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26552; }","duration":"239.596314ms","start":"2026-10-03T03:25:55.061899Z","end":"2026-10-03T03:25:55.301495Z","steps":["trace[1870473554] 'agreement among raft nodes before linearized reading' (duration: 239.48075ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.301467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.156928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T03:25:55.301524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.365545ms","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-10-03T03:25:55.301567Z","caller":"traceutil/trace.go:171","msg":"trace[1258879767] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26552; }","duration":"402.359513ms","start":"2026-10-03T03:25:54.899181Z","end":"2026-10-03T03:25:55.301541Z","steps":["trace[1258879767] 'agreement among raft nodes before linearized reading' (duration: 402.139707ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:55.301592Z","caller":"traceutil/trace.go:171","msg":"trace[1618649980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26552; }","duration":"249.503909ms","start":"2026-10-03T03:25:55.052064Z","end":"2026-10-03T03:25:55.301568Z","steps":["trace[1618649980] 'agreement among raft nodes before linearized reading' (duration: 249.149149ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.301604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.270097ms","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:8"} {"level":"warn","ts":"2026-10-03T03:25:55.301581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.483278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-03T03:25:55.301611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:54.899158Z","time spent":"402.443865ms","remote":"127.0.0.1:56700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-10-03T03:25:55.301637Z","caller":"traceutil/trace.go:171","msg":"trace[1306193192] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:26552; }","duration":"242.323369ms","start":"2026-10-03T03:25:55.059304Z","end":"2026-10-03T03:25:55.301628Z","steps":["trace[1306193192] 'agreement among raft nodes before linearized reading' (duration: 242.267288ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:55.301648Z","caller":"traceutil/trace.go:171","msg":"trace[614122586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26552; }","duration":"249.600101ms","start":"2026-10-03T03:25:55.052033Z","end":"2026-10-03T03:25:55.301633Z","steps":["trace[614122586] 'agreement among raft nodes before linearized reading' (duration: 249.450747ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.301665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.104915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-03T03:25:55.301666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.045893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:55.301713Z","caller":"traceutil/trace.go:171","msg":"trace[85329089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26552; }","duration":"138.194757ms","start":"2026-10-03T03:25:55.163504Z","end":"2026-10-03T03:25:55.301699Z","steps":["trace[85329089] 'agreement among raft nodes before linearized reading' (duration: 138.110225ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:55.301722Z","caller":"traceutil/trace.go:171","msg":"trace[638242797] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26552; }","duration":"228.151995ms","start":"2026-10-03T03:25:55.073553Z","end":"2026-10-03T03:25:55.301705Z","steps":["trace[638242797] 'agreement among raft nodes before linearized reading' (duration: 228.050813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.301806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.121422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T03:25:55.301918Z","caller":"traceutil/trace.go:171","msg":"trace[649748911] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26552; }","duration":"498.297967ms","start":"2026-10-03T03:25:54.803598Z","end":"2026-10-03T03:25:55.301896Z","steps":["trace[649748911] 'agreement among raft nodes before linearized reading' (duration: 498.001588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.301961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:54.803572Z","time spent":"498.379419ms","remote":"127.0.0.1:56596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":125,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-10-03T03:25:55.303505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.144955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-03T03:25:55.303556Z","caller":"traceutil/trace.go:171","msg":"trace[32455945] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26552; }","duration":"403.259048ms","start":"2026-10-03T03:25:54.900284Z","end":"2026-10-03T03:25:55.303543Z","steps":["trace[32455945] 'agreement among raft nodes before linearized reading' (duration: 401.256723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.30359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:54.900234Z","time spent":"403.34706ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2820,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-10-03T03:25:55.588777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.374923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:55.589103Z","caller":"traceutil/trace.go:171","msg":"trace[663797447] transaction","detail":"{read_only:false; response_revision:26557; number_of_response:1; }","duration":"183.050252ms","start":"2026-10-03T03:25:55.406032Z","end":"2026-10-03T03:25:55.589082Z","steps":["trace[663797447] 'process raft request' (duration: 183.005931ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:55.58908Z","caller":"traceutil/trace.go:171","msg":"trace[585568660] transaction","detail":"{read_only:false; response_revision:26554; number_of_response:1; }","duration":"282.241806ms","start":"2026-10-03T03:25:55.306798Z","end":"2026-10-03T03:25:55.589039Z","steps":["trace[585568660] 'process raft request' (duration: 107.497054ms)","trace[585568660] 'compare' (duration: 174.112205ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:55.589393Z","caller":"traceutil/trace.go:171","msg":"trace[513030644] linearizableReadLoop","detail":"{readStateIndex:27410; appliedIndex:27407; }","duration":"259.861575ms","start":"2026-10-03T03:25:55.329516Z","end":"2026-10-03T03:25:55.589377Z","steps":["trace[513030644] 'read index received' (duration: 84.820214ms)","trace[513030644] 'applied index is now lower than readState.Index' (duration: 175.04009ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:55.589396Z","caller":"traceutil/trace.go:171","msg":"trace[954206491] transaction","detail":"{read_only:false; response_revision:26555; number_of_response:1; }","duration":"282.544645ms","start":"2026-10-03T03:25:55.306842Z","end":"2026-10-03T03:25:55.589386Z","steps":["trace[954206491] 'process raft request' (duration: 282.046001ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:55.589381Z","caller":"traceutil/trace.go:171","msg":"trace[2145740143] transaction","detail":"{read_only:false; response_revision:26556; number_of_response:1; }","duration":"273.041231ms","start":"2026-10-03T03:25:55.3163Z","end":"2026-10-03T03:25:55.589341Z","steps":["trace[2145740143] 'process raft request' (duration: 272.691331ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.589599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.540209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:55.589655Z","caller":"traceutil/trace.go:171","msg":"trace[1368599733] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26557; }","duration":"280.627762ms","start":"2026-10-03T03:25:55.309007Z","end":"2026-10-03T03:25:55.589635Z","steps":["trace[1368599733] 'agreement among raft nodes before linearized reading' (duration: 280.507838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.589947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.047256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-03T03:25:55.590001Z","caller":"traceutil/trace.go:171","msg":"trace[1287243180] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26557; }","duration":"257.148689ms","start":"2026-10-03T03:25:55.332838Z","end":"2026-10-03T03:25:55.589987Z","steps":["trace[1287243180] 'agreement among raft nodes before linearized reading' (duration: 256.902163ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:55.94226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.158971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:55.942406Z","caller":"traceutil/trace.go:171","msg":"trace[1680012948] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"313.529236ms","start":"2026-10-03T03:25:55.628852Z","end":"2026-10-03T03:25:55.942382Z","steps":["trace[1680012948] 'process raft request' (duration: 132.123199ms)","trace[1680012948] 'compare' (duration: 181.001726ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:55.942514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:25:55.628825Z","time spent":"313.65339ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:25:55.945445Z","caller":"traceutil/trace.go:171","msg":"trace[246555348] transaction","detail":"{read_only:false; response_revision:26559; number_of_response:1; }","duration":"169.142596ms","start":"2026-10-03T03:25:55.776249Z","end":"2026-10-03T03:25:55.945392Z","steps":["trace[246555348] 'process raft request' (duration: 168.89934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:56.175946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.657257ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:25:56.176117Z","caller":"traceutil/trace.go:171","msg":"trace[1721040968] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26559; }","duration":"108.842402ms","start":"2026-10-03T03:25:56.067251Z","end":"2026-10-03T03:25:56.176093Z","steps":["trace[1721040968] 'range keys from in-memory index tree' (duration: 108.640306ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:56.17659Z","caller":"traceutil/trace.go:171","msg":"trace[300727881] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"275.948323ms","start":"2026-10-03T03:25:55.900609Z","end":"2026-10-03T03:25:56.176557Z","steps":["trace[300727881] 'process raft request' (duration: 189.43814ms)","trace[300727881] 'compare' (duration: 85.546416ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:56.587927Z","caller":"traceutil/trace.go:171","msg":"trace[1325738513] transaction","detail":"{read_only:false; response_revision:26563; number_of_response:1; }","duration":"124.153768ms","start":"2026-10-03T03:25:56.463745Z","end":"2026-10-03T03:25:56.587899Z","steps":["trace[1325738513] 'process raft request' (duration: 123.990894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:25:57.024006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.640174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:25:57.024237Z","caller":"traceutil/trace.go:171","msg":"trace[1905524657] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"201.523786ms","start":"2026-10-03T03:25:56.822691Z","end":"2026-10-03T03:25:57.024215Z","steps":["trace[1905524657] 'process raft request' (duration: 201.444854ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:57.024273Z","caller":"traceutil/trace.go:171","msg":"trace[1880503137] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"206.00755ms","start":"2026-10-03T03:25:56.818222Z","end":"2026-10-03T03:25:57.024229Z","steps":["trace[1880503137] 'process raft request' (duration: 69.043127ms)","trace[1880503137] 'compare' (duration: 136.46852ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:57.077633Z","caller":"traceutil/trace.go:171","msg":"trace[846014661] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"173.123548ms","start":"2026-10-03T03:25:56.904483Z","end":"2026-10-03T03:25:57.077607Z","steps":["trace[846014661] 'process raft request' (duration: 172.793738ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:57.229139Z","caller":"traceutil/trace.go:171","msg":"trace[474064397] transaction","detail":"{read_only:false; response_revision:26567; number_of_response:1; }","duration":"146.905699ms","start":"2026-10-03T03:25:57.082198Z","end":"2026-10-03T03:25:57.229104Z","steps":["trace[474064397] 'process raft request' (duration: 65.17695ms)","trace[474064397] 'compare' (duration: 81.559905ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:57.261039Z","caller":"traceutil/trace.go:171","msg":"trace[1052074211] transaction","detail":"{read_only:false; response_revision:26568; number_of_response:1; }","duration":"170.789843ms","start":"2026-10-03T03:25:57.090218Z","end":"2026-10-03T03:25:57.261008Z","steps":["trace[1052074211] 'process raft request' (duration: 170.040902ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:57.429018Z","caller":"traceutil/trace.go:171","msg":"trace[733035522] transaction","detail":"{read_only:false; response_revision:26569; number_of_response:1; }","duration":"121.197446ms","start":"2026-10-03T03:25:57.30779Z","end":"2026-10-03T03:25:57.428988Z","steps":["trace[733035522] 'process raft request' (duration: 109.918873ms)","trace[733035522] 'compare' (duration: 10.590154ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:25:57.737443Z","caller":"traceutil/trace.go:171","msg":"trace[1837492528] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"140.835321ms","start":"2026-10-03T03:25:57.596561Z","end":"2026-10-03T03:25:57.737396Z","steps":["trace[1837492528] 'process raft request' (duration: 140.651516ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:57.748203Z","caller":"traceutil/trace.go:171","msg":"trace[1427570758] transaction","detail":"{read_only:false; response_revision:26573; number_of_response:1; }","duration":"148.350739ms","start":"2026-10-03T03:25:57.599832Z","end":"2026-10-03T03:25:57.748182Z","steps":["trace[1427570758] 'process raft request' (duration: 148.287818ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:57.748237Z","caller":"traceutil/trace.go:171","msg":"trace[1085253514] transaction","detail":"{read_only:false; response_revision:26572; number_of_response:1; }","duration":"149.200423ms","start":"2026-10-03T03:25:57.599006Z","end":"2026-10-03T03:25:57.748206Z","steps":["trace[1085253514] 'process raft request' (duration: 148.999568ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:57.748356Z","caller":"traceutil/trace.go:171","msg":"trace[132849554] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"148.110333ms","start":"2026-10-03T03:25:57.600219Z","end":"2026-10-03T03:25:57.748329Z","steps":["trace[132849554] 'process raft request' (duration: 147.936088ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:58.164474Z","caller":"traceutil/trace.go:171","msg":"trace[1796536534] transaction","detail":"{read_only:false; response_revision:26576; number_of_response:1; }","duration":"107.711951ms","start":"2026-10-03T03:25:58.056734Z","end":"2026-10-03T03:25:58.164445Z","steps":["trace[1796536534] 'process raft request' (duration: 107.527696ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:58.313469Z","caller":"traceutil/trace.go:171","msg":"trace[1156164537] transaction","detail":"{read_only:false; response_revision:26577; number_of_response:1; }","duration":"131.770429ms","start":"2026-10-03T03:25:58.181618Z","end":"2026-10-03T03:25:58.313389Z","steps":["trace[1156164537] 'process raft request' (duration: 101.340654ms)","trace[1156164537] 'compare' (duration: 30.27574ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:25:58.600272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.835738ms","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-10-03T03:25:58.600368Z","caller":"traceutil/trace.go:171","msg":"trace[831866606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26577; }","duration":"186.943481ms","start":"2026-10-03T03:25:58.413402Z","end":"2026-10-03T03:25:58.600345Z","steps":["trace[831866606] 'range keys from in-memory index tree' (duration: 186.580001ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:58.760924Z","caller":"traceutil/trace.go:171","msg":"trace[480488521] transaction","detail":"{read_only:false; response_revision:26580; number_of_response:1; }","duration":"155.778025ms","start":"2026-10-03T03:25:58.605114Z","end":"2026-10-03T03:25:58.760892Z","steps":["trace[480488521] 'process raft request' (duration: 155.701773ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:25:58.761033Z","caller":"traceutil/trace.go:171","msg":"trace[957628877] transaction","detail":"{read_only:false; response_revision:26579; number_of_response:1; }","duration":"162.40677ms","start":"2026-10-03T03:25:58.598594Z","end":"2026-10-03T03:25:58.761001Z","steps":["trace[957628877] 'process raft request' (duration: 116.726392ms)","trace[957628877] 'compare' (duration: 45.296108ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:26:22.463618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.065638ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-03T03:26:22.463822Z","caller":"traceutil/trace.go:171","msg":"trace[816981567] linearizableReadLoop","detail":"{readStateIndex:27618; appliedIndex:27617; }","duration":"241.664571ms","start":"2026-10-03T03:26:22.222132Z","end":"2026-10-03T03:26:22.463797Z","steps":["trace[816981567] 'read index received' (duration: 56.331615ms)","trace[816981567] 'applied index is now lower than readState.Index' (duration: 185.330846ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:26:22.46404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.890327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1985"} {"level":"info","ts":"2026-10-03T03:26:22.464101Z","caller":"traceutil/trace.go:171","msg":"trace[1808693369] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:26759; }","duration":"242.00037ms","start":"2026-10-03T03:26:22.222079Z","end":"2026-10-03T03:26:22.464079Z","steps":["trace[1808693369] 'agreement among raft nodes before linearized reading' (duration: 241.811015ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:26:22.890002Z","caller":"traceutil/trace.go:171","msg":"trace[677047280] transaction","detail":"{read_only:false; response_revision:26760; number_of_response:1; }","duration":"106.712993ms","start":"2026-10-03T03:26:22.783253Z","end":"2026-10-03T03:26:22.889966Z","steps":["trace[677047280] 'process raft request' (duration: 106.483237ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:26:23.050442Z","caller":"traceutil/trace.go:171","msg":"trace[12532654] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"147.938968ms","start":"2026-10-03T03:26:22.902467Z","end":"2026-10-03T03:26:23.050406Z","steps":["trace[12532654] 'process raft request' (duration: 122.785ms)","trace[12532654] 'compare' (duration: 24.999584ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:26:23.319778Z","caller":"traceutil/trace.go:171","msg":"trace[2052742881] transaction","detail":"{read_only:false; response_revision:26763; number_of_response:1; }","duration":"121.812833ms","start":"2026-10-03T03:26:23.197937Z","end":"2026-10-03T03:26:23.31975Z","steps":["trace[2052742881] 'process raft request' (duration: 121.618538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:23.694339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.571523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:26:23.694582Z","caller":"traceutil/trace.go:171","msg":"trace[2036888154] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"314.974206ms","start":"2026-10-03T03:26:23.379591Z","end":"2026-10-03T03:26:23.694565Z","steps":["trace[2036888154] 'process raft request' (duration: 314.918885ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:26:23.694596Z","caller":"traceutil/trace.go:171","msg":"trace[1481064911] linearizableReadLoop","detail":"{readStateIndex:27624; appliedIndex:27623; }","duration":"346.405129ms","start":"2026-10-03T03:26:23.348157Z","end":"2026-10-03T03:26:23.694562Z","steps":["trace[1481064911] 'read index received' (duration: 111.544727ms)","trace[1481064911] 'applied index is now lower than readState.Index' (duration: 234.857612ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:26:23.69464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:26:23.379567Z","time spent":"315.047899ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:26:23.69468Z","caller":"traceutil/trace.go:171","msg":"trace[648726602] transaction","detail":"{read_only:false; response_revision:26764; number_of_response:1; }","duration":"369.449379ms","start":"2026-10-03T03:26:23.325173Z","end":"2026-10-03T03:26:23.694623Z","steps":["trace[648726602] 'process raft request' (duration: 134.510045ms)","trace[648726602] 'compare' (duration: 234.468331ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:26:23.694776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.627986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-03T03:26:23.694805Z","caller":"traceutil/trace.go:171","msg":"trace[1079741929] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26765; }","duration":"346.700578ms","start":"2026-10-03T03:26:23.348096Z","end":"2026-10-03T03:26:23.694797Z","steps":["trace[1079741929] 'agreement among raft nodes before linearized reading' (duration: 346.575954ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:23.694822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:26:23.348074Z","time spent":"346.743769ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-10-03T03:26:23.694859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:26:23.325148Z","time spent":"369.614444ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-03T03:26:23.733634Z","caller":"traceutil/trace.go:171","msg":"trace[112453164] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"209.497517ms","start":"2026-10-03T03:26:23.52411Z","end":"2026-10-03T03:26:23.733608Z","steps":["trace[112453164] 'process raft request' (duration: 209.335933ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:23.73453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.053246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-03T03:26:23.734606Z","caller":"traceutil/trace.go:171","msg":"trace[892839192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26766; }","duration":"352.165879ms","start":"2026-10-03T03:26:23.382419Z","end":"2026-10-03T03:26:23.734585Z","steps":["trace[892839192] 'agreement among raft nodes before linearized reading' (duration: 351.947133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:23.734654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:26:23.382401Z","time spent":"352.235781ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-03T03:26:23.95062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.663751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:26:23.95079Z","caller":"traceutil/trace.go:171","msg":"trace[1379537417] transaction","detail":"{read_only:false; response_revision:26767; number_of_response:1; }","duration":"250.715532ms","start":"2026-10-03T03:26:23.700043Z","end":"2026-10-03T03:26:23.950759Z","steps":["trace[1379537417] 'process raft request' (duration: 102.757283ms)","trace[1379537417] 'compare' (duration: 147.331942ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:26:23.970111Z","caller":"traceutil/trace.go:171","msg":"trace[1779006893] transaction","detail":"{read_only:false; response_revision:26769; number_of_response:1; }","duration":"228.33134ms","start":"2026-10-03T03:26:23.741739Z","end":"2026-10-03T03:26:23.970071Z","steps":["trace[1779006893] 'process raft request' (duration: 228.240258ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:26:23.970124Z","caller":"traceutil/trace.go:171","msg":"trace[108604161] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"229.566925ms","start":"2026-10-03T03:26:23.74051Z","end":"2026-10-03T03:26:23.970077Z","steps":["trace[108604161] 'process raft request' (duration: 229.208875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:24.213098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.176415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-03T03:26:24.213294Z","caller":"traceutil/trace.go:171","msg":"trace[651845009] linearizableReadLoop","detail":"{readStateIndex:27630; appliedIndex:27627; }","duration":"258.554269ms","start":"2026-10-03T03:26:23.95472Z","end":"2026-10-03T03:26:24.213274Z","steps":["trace[651845009] 'read index received' (duration: 14.987116ms)","trace[651845009] 'applied index is now lower than readState.Index' (duration: 243.564873ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:26:24.213444Z","caller":"traceutil/trace.go:171","msg":"trace[1543771770] transaction","detail":"{read_only:false; response_revision:26770; number_of_response:1; }","duration":"361.405804ms","start":"2026-10-03T03:26:23.852025Z","end":"2026-10-03T03:26:24.213431Z","steps":["trace[1543771770] 'process raft request' (duration: 257.795207ms)","trace[1543771770] 'compare' (duration: 102.870467ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:26:24.213561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-03T03:26:23.851997Z","time spent":"361.511787ms","remote":"127.0.0.1:56500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-03T03:26:24.213742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.867717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-03T03:26:24.213764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.013541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:26:24.213853Z","caller":"traceutil/trace.go:171","msg":"trace[718321645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26770; }","duration":"237.007531ms","start":"2026-10-03T03:26:23.976801Z","end":"2026-10-03T03:26:24.213809Z","steps":["trace[718321645] 'agreement among raft nodes before linearized reading' (duration: 236.729783ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:24.213819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.676443ms","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-10-03T03:26:24.213878Z","caller":"traceutil/trace.go:171","msg":"trace[340687320] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26770; }","duration":"259.196677ms","start":"2026-10-03T03:26:23.954659Z","end":"2026-10-03T03:26:24.213856Z","steps":["trace[340687320] 'agreement among raft nodes before linearized reading' (duration: 259.007752ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:24.213873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.239348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-10-03T03:26:24.213931Z","caller":"traceutil/trace.go:171","msg":"trace[2085343089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26770; }","duration":"217.825668ms","start":"2026-10-03T03:26:23.996084Z","end":"2026-10-03T03:26:24.21391Z","steps":["trace[2085343089] 'agreement among raft nodes before linearized reading' (duration: 217.55413ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:26:24.21397Z","caller":"traceutil/trace.go:171","msg":"trace[325020646] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:26770; }","duration":"183.402892ms","start":"2026-10-03T03:26:24.03054Z","end":"2026-10-03T03:26:24.213943Z","steps":["trace[325020646] 'agreement among raft nodes before linearized reading' (duration: 183.090173ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:26:24.395933Z","caller":"traceutil/trace.go:171","msg":"trace[619660876] linearizableReadLoop","detail":"{readStateIndex:27633; appliedIndex:27630; }","duration":"155.238541ms","start":"2026-10-03T03:26:24.240654Z","end":"2026-10-03T03:26:24.395893Z","steps":["trace[619660876] 'read index received' (duration: 17.333571ms)","trace[619660876] 'applied index is now lower than readState.Index' (duration: 137.90375ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:26:24.396023Z","caller":"traceutil/trace.go:171","msg":"trace[800949430] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"176.595353ms","start":"2026-10-03T03:26:24.219389Z","end":"2026-10-03T03:26:24.395984Z","steps":["trace[800949430] 'process raft request' (duration: 176.455509ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:26:24.39604Z","caller":"traceutil/trace.go:171","msg":"trace[1378028591] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"176.856111ms","start":"2026-10-03T03:26:24.219148Z","end":"2026-10-03T03:26:24.396004Z","steps":["trace[1378028591] 'process raft request' (duration: 176.47718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:26:24.39609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.409995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-03T03:26:24.396122Z","caller":"traceutil/trace.go:171","msg":"trace[19904537] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:26773; }","duration":"155.503078ms","start":"2026-10-03T03:26:24.24061Z","end":"2026-10-03T03:26:24.396113Z","steps":["trace[19904537] 'agreement among raft nodes before linearized reading' (duration: 155.394945ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:29:33.434846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25692} {"level":"info","ts":"2026-10-03T03:29:33.606405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25692,"took":"167.168598ms","hash":178756159,"current-db-size-bytes":58114048,"current-db-size":"58 MB","current-db-size-in-use-bytes":36868096,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-03T03:29:33.606487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":178756159,"revision":25692,"compact-revision":22370} {"level":"info","ts":"2026-10-03T03:30:08.708574Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c43c9f84c6588d4","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T03:30:08.718415Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-03T03:30:08.718577Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-03T03:34:33.445321Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28675} {"level":"info","ts":"2026-10-03T03:34:33.614584Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28675,"took":"164.624009ms","hash":3911484970,"current-db-size-bytes":58114048,"current-db-size":"58 MB","current-db-size-in-use-bytes":32931840,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T03:34:33.614662Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3911484970,"revision":28675,"compact-revision":25692} {"level":"info","ts":"2026-10-03T03:39:33.456425Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31058} {"level":"info","ts":"2026-10-03T03:39:33.603662Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31058,"took":"142.585424ms","hash":2334310863,"current-db-size-bytes":58114048,"current-db-size":"58 MB","current-db-size-in-use-bytes":31363072,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T03:39:33.603762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2334310863,"revision":31058,"compact-revision":28675} {"level":"info","ts":"2026-10-03T03:44:33.462791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33355} {"level":"info","ts":"2026-10-03T03:44:33.622874Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33355,"took":"155.30461ms","hash":3405755142,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":31215616,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-03T03:44:33.622954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3405755142,"revision":33355,"compact-revision":31058} {"level":"info","ts":"2026-10-03T03:46:26.933672Z","caller":"traceutil/trace.go:171","msg":"trace[729083537] transaction","detail":"{read_only:false; response_revision:36590; number_of_response:1; }","duration":"103.939494ms","start":"2026-10-03T03:46:26.829706Z","end":"2026-10-03T03:46:26.933646Z","steps":["trace[729083537] 'process raft request' (duration: 64.871176ms)","trace[729083537] 'compare' (duration: 38.758079ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:46:33.028806Z","caller":"traceutil/trace.go:171","msg":"trace[551074950] transaction","detail":"{read_only:false; response_revision:36632; number_of_response:1; }","duration":"190.720431ms","start":"2026-10-03T03:46:32.838058Z","end":"2026-10-03T03:46:33.028778Z","steps":["trace[551074950] 'process raft request' (duration: 189.953169ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:46:33.049904Z","caller":"traceutil/trace.go:171","msg":"trace[1849547577] linearizableReadLoop","detail":"{readStateIndex:37767; appliedIndex:37765; }","duration":"172.909735ms","start":"2026-10-03T03:46:32.876956Z","end":"2026-10-03T03:46:33.049866Z","steps":["trace[1849547577] 'read index received' (duration: 150.726727ms)","trace[1849547577] 'applied index is now lower than readState.Index' (duration: 22.181438ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:46:33.04998Z","caller":"traceutil/trace.go:171","msg":"trace[934095160] transaction","detail":"{read_only:false; response_revision:36633; number_of_response:1; }","duration":"209.35608ms","start":"2026-10-03T03:46:32.840588Z","end":"2026-10-03T03:46:33.049944Z","steps":["trace[934095160] 'process raft request' (duration: 209.095893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:46:33.050132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.152123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-03T03:46:33.05018Z","caller":"traceutil/trace.go:171","msg":"trace[1095466195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:36633; }","duration":"173.240545ms","start":"2026-10-03T03:46:32.876926Z","end":"2026-10-03T03:46:33.050166Z","steps":["trace[1095466195] 'agreement among raft nodes before linearized reading' (duration: 173.058ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:46:33.734053Z","caller":"traceutil/trace.go:171","msg":"trace[1902288208] linearizableReadLoop","detail":"{readStateIndex:37775; appliedIndex:37774; }","duration":"121.901725ms","start":"2026-10-03T03:46:33.61212Z","end":"2026-10-03T03:46:33.734022Z","steps":["trace[1902288208] 'read index received' (duration: 121.615537ms)","trace[1902288208] 'applied index is now lower than readState.Index' (duration: 285.148µs)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:46:33.734162Z","caller":"traceutil/trace.go:171","msg":"trace[1579616069] transaction","detail":"{read_only:false; response_revision:36641; number_of_response:1; }","duration":"127.543512ms","start":"2026-10-03T03:46:33.606579Z","end":"2026-10-03T03:46:33.734122Z","steps":["trace[1579616069] 'process raft request' (duration: 127.229823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:46:33.7342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.05185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:46:33.734249Z","caller":"traceutil/trace.go:171","msg":"trace[1185968454] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:36641; }","duration":"122.130442ms","start":"2026-10-03T03:46:33.612102Z","end":"2026-10-03T03:46:33.734233Z","steps":["trace[1185968454] 'agreement among raft nodes before linearized reading' (duration: 122.021359ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:46:34.143025Z","caller":"traceutil/trace.go:171","msg":"trace[956563191] transaction","detail":"{read_only:false; response_revision:36643; number_of_response:1; }","duration":"103.133562ms","start":"2026-10-03T03:46:34.039842Z","end":"2026-10-03T03:46:34.142975Z","steps":["trace[956563191] 'process raft request' (duration: 29.797ms)","trace[956563191] 'compare' (duration: 73.062565ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:46:34.373556Z","caller":"traceutil/trace.go:171","msg":"trace[1908332332] linearizableReadLoop","detail":"{readStateIndex:37778; appliedIndex:37777; }","duration":"211.379207ms","start":"2026-10-03T03:46:34.162143Z","end":"2026-10-03T03:46:34.373522Z","steps":["trace[1908332332] 'read index received' (duration: 133.21458ms)","trace[1908332332] 'applied index is now lower than readState.Index' (duration: 78.163237ms)"],"step_count":2} {"level":"info","ts":"2026-10-03T03:46:34.373937Z","caller":"traceutil/trace.go:171","msg":"trace[759505538] transaction","detail":"{read_only:false; response_revision:36644; number_of_response:1; }","duration":"225.578912ms","start":"2026-10-03T03:46:34.148339Z","end":"2026-10-03T03:46:34.373918Z","steps":["trace[759505538] 'process raft request' (duration: 147.099246ms)","trace[759505538] 'compare' (duration: 77.882049ms)"],"step_count":2} {"level":"warn","ts":"2026-10-03T03:46:34.374156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.763804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:46:34.37425Z","caller":"traceutil/trace.go:171","msg":"trace[1121297031] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:36644; }","duration":"138.929679ms","start":"2026-10-03T03:46:34.235295Z","end":"2026-10-03T03:46:34.374225Z","steps":["trace[1121297031] 'agreement among raft nodes before linearized reading' (duration: 138.742854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-03T03:46:34.374216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.039325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-03T03:46:34.374621Z","caller":"traceutil/trace.go:171","msg":"trace[732703811] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:36644; }","duration":"212.470617ms","start":"2026-10-03T03:46:34.162128Z","end":"2026-10-03T03:46:34.374598Z","steps":["trace[732703811] 'agreement among raft nodes before linearized reading' (duration: 211.993623ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:46:35.001269Z","caller":"traceutil/trace.go:171","msg":"trace[1048542334] transaction","detail":"{read_only:false; response_revision:36646; number_of_response:1; }","duration":"207.293023ms","start":"2026-10-03T03:46:34.793949Z","end":"2026-10-03T03:46:35.001242Z","steps":["trace[1048542334] 'process raft request' (duration: 207.084827ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:46:35.027704Z","caller":"traceutil/trace.go:171","msg":"trace[1524718777] transaction","detail":"{read_only:false; response_revision:36647; number_of_response:1; }","duration":"197.548082ms","start":"2026-10-03T03:46:34.830116Z","end":"2026-10-03T03:46:35.027664Z","steps":["trace[1524718777] 'process raft request' (duration: 197.338626ms)"],"step_count":1} {"level":"info","ts":"2026-10-03T03:49:33.469783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35624} {"level":"info","ts":"2026-10-03T03:49:33.634529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35624,"took":"159.535433ms","hash":3209605999,"current-db-size-bytes":59154432,"current-db-size":"59 MB","current-db-size-in-use-bytes":32714752,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-03T03:49:33.634623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3209605999,"revision":35624,"compact-revision":33355} {"level":"info","ts":"2026-10-03T03:51:21.397229Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c43c9f84c6588d4","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-03T03:51:21.404505Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-03T03:51:21.404701Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-03T03:54:33.478027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37953} {"level":"info","ts":"2026-10-03T03:54:33.636001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37953,"took":"151.773741ms","hash":198996506,"current-db-size-bytes":60858368,"current-db-size":"61 MB","current-db-size-in-use-bytes":32268288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T03:54:33.636099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":198996506,"revision":37953,"compact-revision":35624} {"level":"info","ts":"2026-10-03T03:59:33.485885Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40218} {"level":"info","ts":"2026-10-03T03:59:33.630513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40218,"took":"138.391792ms","hash":2150073947,"current-db-size-bytes":60858368,"current-db-size":"61 MB","current-db-size-in-use-bytes":31621120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-03T03:59:33.630589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2150073947,"revision":40218,"compact-revision":37953}